3 # Copyright (c) 2015 All rights reserved
4 # This program and the accompanying materials
5 # are made available under the terms of the Apache License, Version 2.0
6 # which accompanies this distribution, and is available at
8 # http://www.apache.org/licenses/LICENSE-2.0
10 # 0.1: This script boots the VM1 and allocates IP address from Nova
11 # Later, the VM2 boots then execute cloud-init to ping VM1.
12 # After successful ping, both the VMs are deleted.
13 # 0.2: measure test duration and publish results under json format
27 from scp import SCPClient
28 from novaclient import client as novaclient
29 from neutronclient.v2_0 import client as neutronclient
30 from keystoneclient.v2_0 import client as keystoneclient
31 from glanceclient import client as glanceclient
33 pp = pprint.PrettyPrinter(indent=4)
35 parser = argparse.ArgumentParser()
37 parser.add_argument("-d", "--debug", help="Debug mode", action="store_true")
38 parser.add_argument("-r", "--report",
39 help="Create json result file",
41 parser.add_argument("-n", "--noclean",
42 help="Don't clean the created resources for this test.",
45 args = parser.parse_args()
47 """ logging configuration """
49 logger = logging.getLogger('vPing_ssh')
50 logger.setLevel(logging.DEBUG)
52 ch = logging.StreamHandler()
55 ch.setLevel(logging.DEBUG)
57 ch.setLevel(logging.INFO)
59 formatter = logging.Formatter('%(asctime)s - %(name)s'
60 '- %(levelname)s - %(message)s')
62 ch.setFormatter(formatter)
64 paramiko.util.log_to_file("/var/log/paramiko.log")
66 REPO_PATH = os.environ['repos_dir']+'/functest/'
67 if not os.path.exists(REPO_PATH):
68 logger.error("Functest repository directory not found '%s'" % REPO_PATH)
70 sys.path.append(REPO_PATH + "testcases/")
73 with open("/home/opnfv/functest/conf/config_functest.yaml") as f:
74 functest_yaml = yaml.safe_load(f)
77 HOME = os.environ['HOME'] + "/"
80 VM_DELETE_TIMEOUT = 100
81 PING_TIMEOUT = functest_yaml.get("vping").get("ping_timeout")
82 TEST_DB = functest_yaml.get("results").get("test_db_url")
83 NAME_VM_1 = functest_yaml.get("vping").get("vm_name_1")
84 NAME_VM_2 = functest_yaml.get("vping").get("vm_name_2")
85 # GLANCE_IMAGE_NAME = functest_yaml.get("general"). \
86 # get("openstack").get("image_name")
87 GLANCE_IMAGE_NAME = "functest-vping"
88 GLANCE_IMAGE_FILENAME = functest_yaml.get("general"). \
89 get("openstack").get("image_file_name")
90 GLANCE_IMAGE_FORMAT = functest_yaml.get("general"). \
91 get("openstack").get("image_disk_format")
92 GLANCE_IMAGE_PATH = functest_yaml.get("general"). \
93 get("directories").get("dir_functest_data") + "/" + GLANCE_IMAGE_FILENAME
96 FLAVOR = functest_yaml.get("vping").get("vm_flavor")
98 # NEUTRON Private Network parameters
100 NEUTRON_PRIVATE_NET_NAME = functest_yaml.get("vping"). \
101 get("vping_private_net_name")
102 NEUTRON_PRIVATE_SUBNET_NAME = functest_yaml.get("vping"). \
103 get("vping_private_subnet_name")
104 NEUTRON_PRIVATE_SUBNET_CIDR = functest_yaml.get("vping"). \
105 get("vping_private_subnet_cidr")
106 NEUTRON_ROUTER_NAME = functest_yaml.get("vping"). \
107 get("vping_router_name")
109 SECGROUP_NAME = functest_yaml.get("vping"). \
111 SECGROUP_DESCR = functest_yaml.get("vping"). \
112 get("vping_sg_descr")
117 """pretty printing"""
121 def waitVmActive(nova, vm):
123 # sleep and wait for VM status change
125 count = VM_BOOT_TIMEOUT / sleep_time
127 status = functest_utils.get_instance_status(nova, vm)
128 logger.debug("Status: %s" % status)
129 if status == "ACTIVE":
131 if status == "ERROR" or status == "error":
134 logger.debug("Booting a VM timed out...")
137 time.sleep(sleep_time)
141 def waitVmDeleted(nova, vm):
143 # sleep and wait for VM status change
145 count = VM_DELETE_TIMEOUT / sleep_time
147 status = functest_utils.get_instance_status(nova, vm)
151 logger.debug("Timeout")
156 time.sleep(sleep_time)
160 def create_private_neutron_net(neutron):
162 # Check if the network already exists
163 network_id = functest_utils.get_network_id(neutron,NEUTRON_PRIVATE_NET_NAME)
164 subnet_id = functest_utils.get_subnet_id(neutron,NEUTRON_PRIVATE_SUBNET_NAME)
165 router_id = functest_utils.get_router_id(neutron,NEUTRON_ROUTER_NAME)
167 if network_id != '' and subnet_id != '' and router_id != '' :
168 logger.info("Using existing network '%s'..." % NEUTRON_PRIVATE_NET_NAME)
170 neutron.format = 'json'
171 logger.info('Creating neutron network %s...' % NEUTRON_PRIVATE_NET_NAME)
172 network_id = functest_utils. \
173 create_neutron_net(neutron, NEUTRON_PRIVATE_NET_NAME)
177 logger.debug("Network '%s' created successfully" % network_id)
178 logger.debug('Creating Subnet....')
179 subnet_id = functest_utils. \
180 create_neutron_subnet(neutron,
181 NEUTRON_PRIVATE_SUBNET_NAME,
182 NEUTRON_PRIVATE_SUBNET_CIDR,
186 logger.debug("Subnet '%s' created successfully" % subnet_id)
187 logger.debug('Creating Router...')
188 router_id = functest_utils. \
189 create_neutron_router(neutron, NEUTRON_ROUTER_NAME)
194 logger.debug("Router '%s' created successfully" % router_id)
195 logger.debug('Adding router to subnet...')
197 if not functest_utils.add_interface_router(neutron, router_id, subnet_id):
199 logger.debug("Interface added successfully.")
201 logger.debug('Adding gateway to router...')
202 if not functest_utils.add_gateway_router(neutron, router_id):
204 logger.debug("Gateway added successfully.")
206 network_dic = {'net_id': network_id,
207 'subnet_id': subnet_id,
208 'router_id': router_id}
211 def create_security_group(neutron_client):
212 sg_id = functest_utils.get_security_group_id(neutron_client, SECGROUP_NAME)
214 logger.info("Using existing security group '%s'..." % SECGROUP_NAME)
216 logger.info("Creating security group '%s'..." % SECGROUP_NAME)
217 SECGROUP = functest_utils.create_security_group(neutron_client,
221 logger.error("Failed to create the security group...")
224 sg_id = SECGROUP['id']
226 logger.debug("Security group '%s' with ID=%s created successfully." %\
227 (SECGROUP['name'], sg_id))
229 logger.debug("Adding ICMP rules in security group '%s'..." % SECGROUP_NAME)
230 if not functest_utils.create_secgroup_rule(neutron_client, sg_id, \
232 logger.error("Failed to create the security group rule...")
235 logger.debug("Adding SSH rules in security group '%s'..." % SECGROUP_NAME)
236 if not functest_utils.create_secgroup_rule(neutron_client, sg_id, \
237 'ingress', 'tcp', '22', '22'):
238 logger.error("Failed to create the security group rule...")
241 if not functest_utils.create_secgroup_rule(neutron_client, sg_id, \
242 'egress', 'tcp', '22', '22'):
243 logger.error("Failed to create the security group rule...")
247 def cleanup(nova, neutron, image_id, network_dic, sg_id, floatingip):
249 logger.debug("The OpenStack resources are not deleted.")
253 logger.info("Cleaning up...")
254 logger.debug("Deleting image...")
255 if not functest_utils.delete_glance_image(nova, image_id):
256 logger.error("Error deleting the glance image")
258 vm1 = functest_utils.get_instance_by_name(nova, NAME_VM_1)
260 logger.debug("Deleting '%s'..." % NAME_VM_1)
261 nova.servers.delete(vm1)
262 # wait until VMs are deleted
263 if not waitVmDeleted(nova, vm1):
265 "Instance '%s' with cannot be deleted. Status is '%s'" % (
266 NAME_VM_1, functest_utils.get_instance_status(nova, vm1)))
268 logger.debug("Instance %s terminated." % NAME_VM_1)
270 vm2 = functest_utils.get_instance_by_name(nova, NAME_VM_2)
273 logger.debug("Deleting '%s'..." % NAME_VM_2)
274 vm2 = nova.servers.find(name=NAME_VM_2)
275 nova.servers.delete(vm2)
277 if not waitVmDeleted(nova, vm2):
279 "Instance '%s' with cannot be deleted. Status is '%s'" % (
280 NAME_VM_2, functest_utils.get_instance_status(nova, vm2)))
282 logger.debug("Instance %s terminated." % NAME_VM_2)
284 # delete created network
285 logger.debug("Deleting network '%s'..." % NEUTRON_PRIVATE_NET_NAME)
286 net_id = network_dic["net_id"]
287 subnet_id = network_dic["subnet_id"]
288 router_id = network_dic["router_id"]
290 if not functest_utils.remove_interface_router(neutron, router_id,
292 logger.error("Unable to remove subnet '%s' from router '%s'" % (
293 subnet_id, router_id))
296 logger.debug("Interface removed successfully")
297 if not functest_utils.delete_neutron_router(neutron, router_id):
298 logger.error("Unable to delete router '%s'" % router_id)
301 logger.debug("Router deleted successfully")
303 if not functest_utils.delete_neutron_subnet(neutron, subnet_id):
304 logger.error("Unable to delete subnet '%s'" % subnet_id)
308 "Subnet '%s' deleted successfully" % NEUTRON_PRIVATE_SUBNET_NAME)
310 if not functest_utils.delete_neutron_net(neutron, net_id):
311 logger.error("Unable to delete network '%s'" % net_id)
315 "Network '%s' deleted successfully" % NEUTRON_PRIVATE_NET_NAME)
317 if not functest_utils.delete_security_group(neutron, sg_id):
318 logger.error("Unable to delete security group '%s'" % sg_id)
321 "Security group '%s' deleted successfully" % sg_id)
323 logger.debug("Releasing floating ip '%s'..." % floatingip['fip_addr'])
324 if not functest_utils.delete_floating_ip(nova, floatingip['fip_id']):
325 logger.error("Unable to delete floatingip '%s'" % floatingip['fip_addr'])
328 "Floating IP '%s' deleted successfully" % floatingip['fip_addr'])
332 def push_results(start_time_ts, duration, test_status):
334 logger.debug("Pushing result into DB...")
335 scenario = functest_utils.get_scenario(logger)
336 pod_name = functest_utils.get_pod_name(logger)
337 build_tag = functest_utils.get_build_tag(logger)
338 functest_utils.push_results_to_db(TEST_DB,
341 logger, pod_name, scenario, build_tag,
342 payload={'timestart': start_time_ts,
343 'duration': duration,
344 'status': test_status})
346 logger.error("Error pushing results into Database '%s'" % sys.exc_info()[0])
351 creds_nova = functest_utils.get_credentials("nova")
352 nova_client = novaclient.Client('2', **creds_nova)
353 creds_neutron = functest_utils.get_credentials("neutron")
354 neutron_client = neutronclient.Client(**creds_neutron)
355 creds_keystone = functest_utils.get_credentials("keystone")
356 keystone_client = keystoneclient.Client(**creds_keystone)
357 glance_endpoint = keystone_client.service_catalog.url_for(service_type='image',
358 endpoint_type='publicURL')
359 glance_client = glanceclient.Client(1, glance_endpoint,
360 token=keystone_client.auth_token)
366 # Check if the given image exists
367 image_id = functest_utils.get_image_id(glance_client, GLANCE_IMAGE_NAME)
369 logger.info("Using existing image '%s'..." % GLANCE_IMAGE_NAME)
371 logger.info("Creating image '%s' from '%s'..." % (GLANCE_IMAGE_NAME,
373 image_id = functest_utils.create_glance_image(glance_client,
377 logger.error("Failed to create a Glance image...")
379 logger.debug("Image '%s' with ID=%s created successfully." %\
380 (GLANCE_IMAGE_NAME, image_id))
383 network_dic = create_private_neutron_net(neutron_client)
386 "There has been a problem when creating the neutron network")
389 network_id = network_dic["net_id"]
391 sg_id = create_security_group(neutron_client)
393 # Check if the given flavor exists
395 flavor = nova_client.flavors.find(name=FLAVOR)
396 logger.info("Using existing Flavor '%s'..." % FLAVOR)
398 logger.error("Flavor '%s' not found." % FLAVOR)
399 logger.info("Available flavors are: ")
400 pMsg(nova_client.flavor.list())
403 # Deleting instances if they exist
404 servers = nova_client.servers.list()
405 for server in servers:
406 if server.name == NAME_VM_1 or server.name == NAME_VM_2:
407 logger.info("Instance %s found. Deleting..." % server.name)
412 start_time_ts = time.time()
413 end_time_ts = start_time_ts
414 logger.info("vPing Start Time:'%s'" % (
415 datetime.datetime.fromtimestamp(start_time_ts).strftime(
416 '%Y-%m-%d %H:%M:%S')))
418 logger.info("Creating instance '%s'..." % NAME_VM_1)
420 "Configuration:\n name=%s \n flavor=%s \n image=%s \n "
421 "network=%s \n" % (NAME_VM_1, flavor, image_id, network_id))
422 vm1 = nova_client.servers.create(
426 nics=[{"net-id": network_id}]
429 # wait until VM status is active
430 if not waitVmActive(nova_client, vm1):
431 logger.error("Instance '%s' cannot be booted. Status is '%s'" % (
432 NAME_VM_1, functest_utils.get_instance_status(nova_client, vm1)))
433 cleanup(nova_client, neutron_client, image_id, network_dic, sg_id, floatingip)
436 logger.info("Instance '%s' is ACTIVE." % NAME_VM_1)
438 # Retrieve IP of first VM
439 test_ip = vm1.networks.get(NEUTRON_PRIVATE_NET_NAME)[0]
440 logger.debug("Instance '%s' got private ip '%s'." % (NAME_VM_1, test_ip))
442 logger.info("Adding '%s' to security group '%s'..." % (NAME_VM_1, SECGROUP_NAME))
443 functest_utils.add_secgroup_to_instance(nova_client, vm1.id, sg_id)
446 logger.info("Creating instance '%s'..." % NAME_VM_2)
448 "Configuration:\n name=%s \n flavor=%s \n image=%s \n "
449 "network=%s \n" % (NAME_VM_2, flavor, image_id, network_id))
450 vm2 = nova_client.servers.create(
454 nics=[{"net-id": network_id}]
457 if not waitVmActive(nova_client, vm2):
458 logger.error("Instance '%s' cannot be booted. Status is '%s'" % (
459 NAME_VM_2, functest_utils.get_instance_status(nova_client, vm2)))
460 cleanup(nova_client, neutron_client, image_id, network_dic, sg_id, floatip_dic)
463 logger.info("Instance '%s' is ACTIVE." % NAME_VM_2)
465 logger.info("Adding '%s' to security group '%s'..." % (NAME_VM_2, SECGROUP_NAME))
466 functest_utils.add_secgroup_to_instance(nova_client, vm2.id, sg_id)
468 logger.info("Creating floating IP for VM '%s'..." % NAME_VM_2)
469 floatip_dic = functest_utils.create_floating_ip(neutron_client)
470 floatip = floatip_dic['fip_addr']
471 floatip_id = floatip_dic['fip_id']
474 logger.error("Cannot create floating IP.")
475 cleanup(nova_client, neutron_client, image_id, network_dic, sg_id, floatip_dic)
477 logger.info("Floating IP created: '%s'" % floatip)
479 logger.info("Associating floating ip: '%s' to VM '%s' " % (floatip, NAME_VM_2))
480 if not functest_utils.add_floating_ip(nova_client, vm2.id, floatip):
481 logger.error("Cannot associate floating IP to VM.")
482 cleanup(nova_client, neutron_client, image_id, network_dic, sg_id, floatip_dic)
485 logger.info("Trying to establish SSH connection to %s..." % floatip)
488 ssh = paramiko.SSHClient()
489 ssh.set_missing_host_key_policy(paramiko.AutoAddPolicy())
495 cidr_first_octet = NEUTRON_PRIVATE_SUBNET_CIDR.split('.')[0]
498 ssh.connect(floatip, username=username, password=password, timeout=2)
499 logger.debug("SSH connection established to %s." % floatip)
502 logger.debug("Waiting for %s..." % floatip)
506 console_log = vm2.get_console_output()
508 # print each "Sending discover" captured on the console log
509 if len(re.findall("Sending discover",console_log)) > discover_count and not got_ip:
511 logger.debug("Console-log '%s': Sending discover..." % NAME_VM_2)
513 # check if eth0 got an ip, the line looks like this: "inet addr:192.168."....
514 # if the dhcp agent fails to assing ip, this line will not appear
515 if "inet addr:"+cidr_first_octet in console_log and not got_ip:
517 logger.debug("The instance '%s' succeeded to get the IP from the dhcp agent.")
519 # if dhcp doesn't work, it shows "No lease, failing". The test will fail...
520 if "No lease, failing" in console_log and not nolease and not got_ip:
522 logger.debug("Console-log '%s': No lease, failing..." % NAME_VM_2)
523 logger.info("The instance failed to get an IP from "\
524 "the DHCP agent. The test will probably timeout...")
527 if timeout == 0: # 300 sec timeout (5 min)
528 logger.error("Cannot establish connection to IP '%s'. Aborting" % floatip)
529 cleanup(nova_client, neutron_client, image_id, network_dic, sg_id, floatip_dic)
532 scp = SCPClient(ssh.get_transport())
534 ping_script = REPO_PATH + "testcases/vPing/CI/libraries/ping.sh"
536 scp.put(ping_script,"~/")
538 logger.error("Cannot SCP the file '%s' to VM '%s'" % (ping_script,floatip))
541 cmd = 'chmod 755 ~/ping.sh'
542 (stdin, stdout, stderr) = ssh.exec_command(cmd)
543 for line in stdout.readlines():
546 logger.info("Waiting for ping...")
550 cmd = '~/ping.sh ' + test_ip
554 (stdin, stdout, stderr) = ssh.exec_command(cmd)
555 output = stdout.readlines()
559 if "vPing OK" in line:
560 logger.info("vPing detected!")
562 # we consider start time at VM1 booting
563 end_time_ts = time.time()
564 duration = round(end_time_ts - start_time_ts, 1)
565 logger.info("vPing duration:'%s' s." % duration)
569 elif sec == PING_TIMEOUT:
570 logger.info("Timeout reached.")
575 logger.debug("Pinging %s. Waiting for response..." % test_ip)
578 cleanup(nova_client, neutron_client, image_id, network_dic, sg_id, floatip_dic)
582 logger.info("vPing OK")
586 logger.error("vPing FAILED")
589 push_results(start_time_ts, duration, test_status)
593 if __name__ == '__main__':