2025-10-23 04:14:52,857 - INFO - Domain Default found not creating 2025-10-23 04:14:53,141 - INFO - Project ctest-TestMd5testsOnControl-49917783 not found, creating it 2025-10-23 04:14:53,622 - INFO - Created Project:ctest-TestMd5testsOnControl-49917783, ID : 9b27c4de-2a7c-4345-8d63-a12cce327ff6 2025-10-23 04:14:55,940 - DEBUG - Response for create_network : {'network': {'id': '729d89da-028d-4ec4-a702-151e8eb57915', 'name': 'test_vnv6sr', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:14:56,185 - DEBUG - Response for create_subnet : {'subnet': {'id': '4edc548a-98a1-417a-9c5f-e8753456ec81', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '729d89da-028d-4ec4-a702-151e8eb57915', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:14:56,443 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fad2cd5f-0331-49d8-948e-5ad8ca3567aa', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '729d89da-028d-4ec4-a702-151e8eb57915', 'ip_version': 4, 'cidr': '100.18.39.192/26', 'allocation_pools': [{'start': '100.18.39.194', 'end': '100.18.39.254'}], 'gateway_ip': '100.18.39.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '100.18.39.194', 'tags': [], 'project_id': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:14:56,478 - INFO - Created VN test_vnv6sr 2025-10-23 04:14:56,490 - DEBUG - VN test_vnv6sr UUID is 729d89da-028d-4ec4-a702-151e8eb57915 2025-10-23 04:14:56,869 - DEBUG - Response for create_network : {'network': {'id': 'b61dac36-ebe9-4a97-885d-2ec04d301055', 'name': 'test_vnv6dn', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:14:57,110 - DEBUG - Response for create_subnet : {'subnet': {'id': '2871d60c-4ca8-46bd-bc57-e2bcb6cd0794', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': 'b61dac36-ebe9-4a97-885d-2ec04d301055', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:14:57,321 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e4a81acc-c389-4c59-8b71-2df1cc8e5507', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': 'b61dac36-ebe9-4a97-885d-2ec04d301055', 'ip_version': 4, 'cidr': '152.39.219.128/26', 'allocation_pools': [{'start': '152.39.219.130', 'end': '152.39.219.190'}], 'gateway_ip': '152.39.219.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '152.39.219.130', 'tags': [], 'project_id': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:14:57,345 - INFO - Created VN test_vnv6dn 2025-10-23 04:14:57,360 - DEBUG - VN test_vnv6dn UUID is b61dac36-ebe9-4a97-885d-2ec04d301055 2025-10-23 04:14:57,531 - DEBUG - Services list from nova: [, , , ] 2025-10-23 04:14:58,774 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2) 2025-10-23 04:15:00,204 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1) 2025-10-23 04:15:00,204 - INFO - Waiting for VM source_vm to be up.. 2025-10-23 04:15:00,306 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-23 04:15:05,396 - DEBUG - VM is in ACTIVE state now 2025-10-23 04:15:05,396 - INFO - VM name : source_vm 2025-10-23 04:15:05,495 - DEBUG - VM source_vm ID is f8953eb8-fe05-40eb-9e76-3e84493b9bec 2025-10-23 04:15:05,524 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4448-2 2025-10-23 04:15:05,620 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/f8953eb8-fe05-40eb-9e76-3e84493b9bec 2025-10-23 04:15:05,962 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/f8953eb8-fe05-40eb-9e76-3e84493b9bec 2025-10-23 04:15:05,998 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/1b4bf2ba-45da-40c3-8e77-2d4eae357575 2025-10-23 04:15:09,189 - 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 1013ms') 2025-10-23 04:15:09,189 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-23 04:15:09,203 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-49917783:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-23 04:15:09,204 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-49917783:test_vnv6sr is 100.18.39.193 and allocation pool is NOT set 2025-10-23 04:15:11,267 - 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.99 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.21 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.213/4.599/7.986/3.386 ms') 2025-10-23 04:15:11,268 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-23 04:15:11,344 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 04:15:11,344 - DEBUG - Waiting to SSH to VM source_vm, IP 100.18.39.195, Port 22 2025-10-23 04:15:11,415 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-23 04:15:11,510 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-23 04:15:16,510 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 04:15:16,511 - DEBUG - Waiting to SSH to VM source_vm, IP 100.18.39.195, Port 22 2025-10-23 04:15:16,681 - DEBUG - VM source_vm is ready for SSH connections 2025-10-23 04:15:16,681 - INFO - Waiting for VM dest_vm to be up.. 2025-10-23 04:15:16,766 - DEBUG - VM is in ACTIVE state now 2025-10-23 04:15:16,766 - INFO - VM name : dest_vm 2025-10-23 04:15:16,855 - DEBUG - VM dest_vm ID is cfc08388-e4cd-4afd-9d79-cfbcc3bf5827 2025-10-23 04:15:16,856 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4448-1 2025-10-23 04:15:16,943 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/cfc08388-e4cd-4afd-9d79-cfbcc3bf5827 2025-10-23 04:15:16,959 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/a5e38d6d-fc4e-450b-9c5f-22d6fa83f65c 2025-10-23 04:15:18,126 - 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.17 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.402 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.402/2.285/4.168/1.883 ms') 2025-10-23 04:15:18,126 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-23 04:15:18,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 04:15:18,199 - DEBUG - Waiting to SSH to VM dest_vm, IP 152.39.219.131, Port 22 2025-10-23 04:15:18,369 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-23 04:15:18,375 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-49917783: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-49917783:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-23 04:15:18,699 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-23 04:15:19,079 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-23 04:15:19,339 - INFO - Configure the policy with allow any 2025-10-23 04:15:19,663 - DEBUG - Response for create_network : {'network': {'id': 'f5a0a11e-c901-4ec2-a6f8-4ad45d931029', 'name': 'vn1', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:15:19,900 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ccd2dcf3-e906-45b6-a2b4-b4717bf1bd7f', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': 'f5a0a11e-c901-4ec2-a6f8-4ad45d931029', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:15:19,928 - INFO - Created VN vn1 2025-10-23 04:15:19,939 - DEBUG - VN vn1 UUID is f5a0a11e-c901-4ec2-a6f8-4ad45d931029 2025-10-23 04:15:20,331 - DEBUG - Response for create_network : {'network': {'id': '9d99bd22-34dd-4c27-a98d-2514a7883ecf', 'name': 'vn2', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:15:20,624 - DEBUG - Response for create_subnet : {'subnet': {'id': '32acf966-c108-45ff-9829-90169f811b18', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '9d99bd22-34dd-4c27-a98d-2514a7883ecf', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:15:20,669 - INFO - Created VN vn2 2025-10-23 04:15:20,681 - DEBUG - VN vn2 UUID is 9d99bd22-34dd-4c27-a98d-2514a7883ecf 2025-10-23 04:15:20,777 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-49917783: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-49917783:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-23 04:15:21,116 - INFO - Attaching policy sec_grp_policy_ctest-random-85375237 to vn vn1 2025-10-23 04:15:21,502 - INFO - Attaching policy sec_grp_policy_ctest-random-85375237 to vn vn2 2025-10-23 04:15:23,270 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2) 2025-10-23 04:15:24,946 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1) 2025-10-23 04:15:25,008 - INFO - ================================================================================ 2025-10-23 04:15:25,008 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-10-23 04:15:25,008 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-10-23 04:15:26,362 - DEBUG - Skipping xmpp flap check 2025-10-23 04:15:26,362 - INFO - Initial checks done. Running the testcase now 2025-10-23 04:15:26,362 - INFO - 2025-10-23 04:15:26,362 - INFO - config per peer with auth_data None 2025-10-23 04:15:26,893 - INFO - configure MD5 on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:15:26,972 - INFO - config per peer with auth_data None 2025-10-23 04:15:27,572 - INFO - configure MD5 on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:15:27,660 - INFO - config per peer with auth_data None 2025-10-23 04:15:28,228 - INFO - configure MD5 on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:15:28,313 - INFO - check BGP peering is up before setting md5 config 2025-10-23 04:15:28,314 - INFO - Check BGP staus between peers 2025-10-23 04:15:28,332 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-10-23 04:15:28,921 - INFO - Check BGP staus between peers 2025-10-23 04:15:28,935 - DEBUG - Check the BGP connection on 10.0.0.41 2025-10-23 04:15:28,935 - DEBUG - Check the BGP connection on 10.0.0.41 2025-10-23 04:15:38,936 - INFO - Check BGP staus between peers 2025-10-23 04:15:38,946 - INFO - remove md5 configured on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:15:38,946 - INFO - config per peer with auth_data None 2025-10-23 04:15:39,494 - INFO - configure MD5 on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:15:39,572 - INFO - remove md5 configured on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:15:39,573 - INFO - config per peer with auth_data None 2025-10-23 04:15:40,156 - INFO - configure MD5 on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:15:40,240 - INFO - remove md5 configured on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:15:40,240 - INFO - config per peer with auth_data None 2025-10-23 04:15:40,860 - INFO - configure MD5 on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:15:40,944 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-23 04:15:40,944 - INFO - Deleting VM vn2_vm_0 2025-10-23 04:15:40,944 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-23 04:15:40,945 - ERROR - Nova failed to get host of the VM 2025-10-23 04:15:40,945 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-23 04:15:40,945 - ERROR - Nova failed to get host of the VM 2025-10-23 04:15:41,101 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-23 04:15:41,101 - INFO - Deleting VM vn1_vm_0 2025-10-23 04:15:41,101 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-23 04:15:41,101 - ERROR - Nova failed to get host of the VM 2025-10-23 04:15:41,101 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-23 04:15:41,101 - ERROR - Nova failed to get host of the VM 2025-10-23 04:15:41,225 - INFO - Dettaching policy sec_grp_policy_ctest-random-85375237 from vn vn2 2025-10-23 04:15:41,390 - INFO - Dettaching policy sec_grp_policy_ctest-random-85375237 from vn vn1 2025-10-23 04:15:41,644 - INFO - Deleted policy sec_grp_policy_ctest-random-85375237 2025-10-23 04:15:41,644 - INFO - Deleting VN vn2 2025-10-23 04:15:41,714 - DEBUG - VN 9d99bd22-34dd-4c27-a98d-2514a7883ecf still in use: Unable to complete operation on network 9d99bd22-34dd-4c27-a98d-2514a7883ecf. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bbcab19f-c26d-4448-90b3-bfa4b5601448'] 2025-10-23 04:15:41,714 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-23 04:15:43,865 - DEBUG - Response for deleting network () 2025-10-23 04:15:43,865 - INFO - Deleting VN vn1 2025-10-23 04:15:44,086 - DEBUG - Response for deleting network () 2025-10-23 04:15:44,086 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-23 04:15:44,256 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-23 04:15:44,502 - INFO - Deleted policy allow_all 2025-10-23 04:15:44,502 - INFO - Deleting VM dest_vm 2025-10-23 04:15:44,602 - INFO - Deleting VM source_vm 2025-10-23 04:15:44,684 - INFO - Deleting VN test_vnv6dn 2025-10-23 04:15:44,741 - DEBUG - VN b61dac36-ebe9-4a97-885d-2ec04d301055 still in use: Unable to complete operation on network b61dac36-ebe9-4a97-885d-2ec04d301055. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4ea37ae3-b567-4fd7-834a-a4985578eb3b'] 2025-10-23 04:15:44,741 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-23 04:15:46,967 - DEBUG - Response for deleting network () 2025-10-23 04:15:46,967 - INFO - Deleting VN test_vnv6sr 2025-10-23 04:15:47,154 - DEBUG - Response for deleting network () 2025-10-23 04:15:48,438 - DEBUG - Skipping xmpp flap check
2025-10-23 04:15:48,438 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-10-23 04:15:48,439 - INFO - -------------------------------------------------------------------------------- 2025-10-23 04:15:48,726 - DEBUG - Response for create_network : {'network': {'id': '95a16911-2923-405c-acd9-5fffad281cfd', 'name': 'test_vnv6sr', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:15:49,000 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b0c6690e-9160-43b9-986b-17d445a06652', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '95a16911-2923-405c-acd9-5fffad281cfd', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:15:49,225 - DEBUG - Response for create_subnet : {'subnet': {'id': '57831159-31ac-4999-9a39-f67202697855', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '95a16911-2923-405c-acd9-5fffad281cfd', 'ip_version': 4, 'cidr': '6.198.116.192/26', 'allocation_pools': [{'start': '6.198.116.194', 'end': '6.198.116.254'}], 'gateway_ip': '6.198.116.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.198.116.194', 'tags': [], 'project_id': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:15:49,252 - INFO - Created VN test_vnv6sr 2025-10-23 04:15:49,266 - DEBUG - VN test_vnv6sr UUID is 95a16911-2923-405c-acd9-5fffad281cfd 2025-10-23 04:15:49,664 - DEBUG - Response for create_network : {'network': {'id': '534e2883-54ed-451e-a870-e95f89660629', 'name': 'test_vnv6dn', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:15:49,889 - DEBUG - Response for create_subnet : {'subnet': {'id': '6f242519-e5f4-4c0b-8f81-2af28f8803a4', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '534e2883-54ed-451e-a870-e95f89660629', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:15:50,108 - DEBUG - Response for create_subnet : {'subnet': {'id': '360f955f-d26e-478b-b8b1-e371dba30b20', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '534e2883-54ed-451e-a870-e95f89660629', 'ip_version': 4, 'cidr': '33.62.241.0/26', 'allocation_pools': [{'start': '33.62.241.2', 'end': '33.62.241.62'}], 'gateway_ip': '33.62.241.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '33.62.241.2', 'tags': [], 'project_id': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:15:50,138 - INFO - Created VN test_vnv6dn 2025-10-23 04:15:50,155 - DEBUG - VN test_vnv6dn UUID is 534e2883-54ed-451e-a870-e95f89660629 2025-10-23 04:15:51,417 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2) 2025-10-23 04:15:52,981 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1) 2025-10-23 04:15:52,981 - INFO - Waiting for VM source_vm to be up.. 2025-10-23 04:15:53,093 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-23 04:15:58,176 - DEBUG - VM is in ACTIVE state now 2025-10-23 04:15:58,176 - INFO - VM name : source_vm 2025-10-23 04:15:58,292 - DEBUG - VM source_vm ID is 41d1bc99-eaaf-4fbb-9234-bbfb661fd9d2 2025-10-23 04:15:58,292 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4448-2 2025-10-23 04:15:58,371 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/41d1bc99-eaaf-4fbb-9234-bbfb661fd9d2 2025-10-23 04:15:58,381 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/5b66f189-c08a-4449-8c6b-a35e0a2b0626 2025-10-23 04:16:01,566 - 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 1025ms') 2025-10-23 04:16:01,566 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-23 04:16:01,585 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-49917783:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-23 04:16:01,585 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-49917783:test_vnv6sr is 6.198.116.193 and allocation pool is NOT set 2025-10-23 04:16:03,655 - 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=12.6 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.550 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.550/6.574/12.598/6.024 ms') 2025-10-23 04:16:03,655 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-23 04:16:03,722 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 04:16:03,722 - DEBUG - Waiting to SSH to VM source_vm, IP 6.198.116.195, Port 22 2025-10-23 04:16:03,799 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-23 04:16:03,906 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-23 04:16:08,908 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 04:16:08,908 - DEBUG - Waiting to SSH to VM source_vm, IP 6.198.116.195, Port 22 2025-10-23 04:16:09,077 - DEBUG - VM source_vm is ready for SSH connections 2025-10-23 04:16:09,078 - INFO - Waiting for VM dest_vm to be up.. 2025-10-23 04:16:09,162 - DEBUG - VM is in ACTIVE state now 2025-10-23 04:16:09,163 - INFO - VM name : dest_vm 2025-10-23 04:16:09,249 - DEBUG - VM dest_vm ID is a5c2ce64-ef3a-42db-8620-b1e06b3fa3f0 2025-10-23 04:16:09,249 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4448-1 2025-10-23 04:16:09,341 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/a5c2ce64-ef3a-42db-8620-b1e06b3fa3f0 2025-10-23 04:16:09,351 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/1eebd861-4f18-4468-ada8-fb5849f2facb 2025-10-23 04:16:10,517 - 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.76 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.463 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.463/2.109/3.755/1.646 ms') 2025-10-23 04:16:10,517 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-23 04:16:10,586 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 04:16:10,587 - DEBUG - Waiting to SSH to VM dest_vm, IP 33.62.241.3, Port 22 2025-10-23 04:16:10,752 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-23 04:16:10,760 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-49917783: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-49917783:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-23 04:16:11,097 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-23 04:16:11,475 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-23 04:16:11,771 - INFO - Configure the policy with allow any 2025-10-23 04:16:12,152 - DEBUG - Response for create_network : {'network': {'id': '24a77b6f-9136-47f6-af4d-d4a4e14830e8', 'name': 'vn1', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:16:12,386 - DEBUG - Response for create_subnet : {'subnet': {'id': '36e6ba01-f37f-4d55-805b-685ec4111655', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': '24a77b6f-9136-47f6-af4d-d4a4e14830e8', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:16:12,418 - INFO - Created VN vn1 2025-10-23 04:16:12,430 - DEBUG - VN vn1 UUID is 24a77b6f-9136-47f6-af4d-d4a4e14830e8 2025-10-23 04:16:12,787 - DEBUG - Response for create_network : {'network': {'id': 'ad6dbc6a-7343-46a3-bba7-02526f75c768', 'name': 'vn2', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'project_id': '9b27c4de2a7c43458d63a12cce327ff6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-49917783', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 04:16:13,049 - DEBUG - Response for create_subnet : {'subnet': {'id': '27cb35c7-c587-4b64-98a8-080663d7711c', 'name': '', 'tenant_id': '9b27c4de2a7c43458d63a12cce327ff6', 'network_id': 'ad6dbc6a-7343-46a3-bba7-02526f75c768', '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': '9b27c4de2a7c43458d63a12cce327ff6'}} 2025-10-23 04:16:13,075 - INFO - Created VN vn2 2025-10-23 04:16:13,086 - DEBUG - VN vn2 UUID is ad6dbc6a-7343-46a3-bba7-02526f75c768 2025-10-23 04:16:13,164 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-49917783: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-49917783:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-23 04:16:13,487 - INFO - Attaching policy sec_grp_policy_ctest-random-17162772 to vn vn1 2025-10-23 04:16:13,850 - INFO - Attaching policy sec_grp_policy_ctest-random-17162772 to vn vn2 2025-10-23 04:16:15,505 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-2) 2025-10-23 04:16:17,001 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4448-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4448-1) 2025-10-23 04:16:17,064 - INFO - ================================================================================ 2025-10-23 04:16:17,064 - INFO - STARTING TEST : test_create_md5_on_control 2025-10-23 04:16:17,064 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-10-23 04:16:18,359 - DEBUG - Skipping xmpp flap check 2025-10-23 04:16:18,359 - INFO - Initial checks done. Running the testcase now 2025-10-23 04:16:18,359 - INFO - 2025-10-23 04:16:18,359 - INFO - config md5 on each host with auth_data None 2025-10-23 04:16:18,360 - INFO - config per peer with auth_data None 2025-10-23 04:16:18,920 - INFO - configure MD5 on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:16:19,000 - INFO - config per peer with auth_data None 2025-10-23 04:16:19,628 - INFO - configure MD5 on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:16:19,712 - INFO - config per peer with auth_data None 2025-10-23 04:16:20,360 - INFO - configure MD5 on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:16:20,448 - INFO - check if BGP between peers are up before setting md5 2025-10-23 04:16:20,448 - INFO - Check BGP staus between peers 2025-10-23 04:16:20,464 - INFO - setting MD5 config on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:16:20,465 - INFO - configure MD5 on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:16:20,576 - INFO - setting MD5 config on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:16:20,576 - INFO - configure MD5 on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:16:20,656 - INFO - setting MD5 config on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:16:20,656 - INFO - configure MD5 on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:16:20,744 - INFO - check if BGP peering is up after md5 config 2025-10-23 04:16:20,744 - INFO - Check BGP staus between peers 2025-10-23 04:16:20,754 - DEBUG - Check the BGP connection on 10.0.0.41 2025-10-23 04:16:20,754 - DEBUG - Check the BGP connection on 10.0.0.41 2025-10-23 04:16:30,756 - INFO - Check BGP staus between peers 2025-10-23 04:16:30,767 - INFO - remove md5 configured on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:16:30,768 - INFO - config per peer with auth_data None 2025-10-23 04:16:31,309 - INFO - configure MD5 on host 946ae783-a0a7-430a-b6d2-0e6d408dd330 2025-10-23 04:16:31,396 - INFO - remove md5 configured on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:16:31,396 - INFO - config per peer with auth_data None 2025-10-23 04:16:31,956 - INFO - configure MD5 on host bd948bba-2c1e-4f51-ad14-ca7733ae5f33 2025-10-23 04:16:32,044 - INFO - remove md5 configured on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:16:32,044 - INFO - config per peer with auth_data None 2025-10-23 04:16:32,628 - INFO - configure MD5 on host 090eb06f-c68f-4d0d-94a4-02c725db8126 2025-10-23 04:16:32,712 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-23 04:16:32,712 - INFO - Deleting VM vn2_vm_0 2025-10-23 04:16:32,712 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-23 04:16:32,712 - ERROR - Nova failed to get host of the VM 2025-10-23 04:16:32,712 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-23 04:16:32,713 - ERROR - Nova failed to get host of the VM 2025-10-23 04:16:32,854 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-23 04:16:32,854 - INFO - Deleting VM vn1_vm_0 2025-10-23 04:16:32,854 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-23 04:16:32,854 - ERROR - Nova failed to get host of the VM 2025-10-23 04:16:32,854 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-23 04:16:32,855 - ERROR - Nova failed to get host of the VM 2025-10-23 04:16:32,935 - INFO - Dettaching policy sec_grp_policy_ctest-random-17162772 from vn vn2 2025-10-23 04:16:33,100 - INFO - Dettaching policy sec_grp_policy_ctest-random-17162772 from vn vn1 2025-10-23 04:16:33,356 - INFO - Deleted policy sec_grp_policy_ctest-random-17162772 2025-10-23 04:16:33,357 - INFO - Deleting VN vn2 2025-10-23 04:16:33,405 - DEBUG - VN ad6dbc6a-7343-46a3-bba7-02526f75c768 still in use: Unable to complete operation on network ad6dbc6a-7343-46a3-bba7-02526f75c768. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-71b9466d-e91a-4ca6-9198-566108ce6796'] 2025-10-23 04:16:33,405 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-23 04:16:35,564 - DEBUG - Response for deleting network () 2025-10-23 04:16:35,564 - INFO - Deleting VN vn1 2025-10-23 04:16:35,751 - DEBUG - Response for deleting network () 2025-10-23 04:16:35,751 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-23 04:16:35,964 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-23 04:16:36,257 - INFO - Deleted policy allow_all 2025-10-23 04:16:36,257 - INFO - Deleting VM dest_vm 2025-10-23 04:16:36,352 - INFO - Deleting VM source_vm 2025-10-23 04:16:36,433 - INFO - Deleting VN test_vnv6dn 2025-10-23 04:16:36,478 - DEBUG - VN 534e2883-54ed-451e-a870-e95f89660629 still in use: Unable to complete operation on network 534e2883-54ed-451e-a870-e95f89660629. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-54f52b10-4b96-4cb8-b84a-2002ffbf45b8'] 2025-10-23 04:16:36,478 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-23 04:16:38,687 - DEBUG - Response for deleting network () 2025-10-23 04:16:38,687 - INFO - Deleting VN test_vnv6sr 2025-10-23 04:16:38,862 - DEBUG - Response for deleting network () 2025-10-23 04:16:40,143 - DEBUG - Skipping xmpp flap check
2025-10-23 04:16:40,144 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-10-23 04:16:40,144 - INFO - -------------------------------------------------------------------------------- 2025-10-23 04:16:40,940 - INFO - Deleted project: ctest-TestMd5testsOnControl-49917783, ID : 9b27c4de-2a7c-4345-8d63-a12cce327ff6