X-Git-Url: https://osm.etsi.org/gitweb/?a=blobdiff_plain;f=lcm%2Fosm_lcm%2Flcm.py;h=2b62c1f4fd5bf4ef0016b756b900a40ff7837a0e;hb=54d9e2152d39930d6a7e43d25816671e56c91523;hp=9bff8c196ee75b8cd5ed476b095c65cefbfcc9b1;hpb=c887cc2fdd02c898ecd1f08d6927043027fd07eb;p=osm%2FRO.git diff --git a/lcm/osm_lcm/lcm.py b/lcm/osm_lcm/lcm.py index 9bff8c19..2b62c1f4 100644 --- a/lcm/osm_lcm/lcm.py +++ b/lcm/osm_lcm/lcm.py @@ -9,16 +9,20 @@ import dbmongo import fslocal import msglocal import msgkafka +import logging +import functools +import sys from dbbase import DbException from fsbase import FsException from msgbase import MsgException from os import environ -import logging -from vca import DeployApplication, RemoveApplication +# from vca import DeployApplication, RemoveApplication +from n2vc.vnf import N2VC +# import os.path +# import time -#streamformat = "%(asctime)s %(name)s %(levelname)s: %(message)s" -streamformat = "%(name)s %(levelname)s: %(message)s" -logging.basicConfig(format=streamformat, level=logging.DEBUG) +from copy import deepcopy +from http import HTTPStatus class LcmException(Exception): @@ -33,23 +37,80 @@ class Lcm: :param config: two level dictionary with configuration. Top level should contain 'database', 'storage', :return: None """ + + self.db = None + self.msg = None + self.fs = None # contains created tasks/futures to be able to cancel - self.lcm_tasks = {} + + self.lcm_ns_tasks = {} + self.lcm_vim_tasks = {} + self.lcm_sdn_tasks = {} # logging self.logger = logging.getLogger('lcm') # load configuration config = self.read_config_file(config_file) self.config = config - self.ro_url = "http://{}:{}/openmano".format(config["RO"]["host"], config["RO"]["port"]) - self.ro_tenant = config["RO"]["tenant"] + self.ro_config={ + "endpoint_url": "http://{}:{}/openmano".format(config["RO"]["host"], config["RO"]["port"]), + "tenant": config.get("tenant", "osm"), + "logger_name": "lcm.ROclient", + "loglevel": "ERROR", + } + self.vca = config["VCA"] # TODO VCA self.loop = None + + # logging + log_format_simple = "%(asctime)s %(levelname)s %(name)s %(filename)s:%(lineno)s %(message)s" + log_formatter_simple = logging.Formatter(log_format_simple, datefmt='%Y-%m-%dT%H:%M:%S') + config["database"]["logger_name"] = "lcm.db" + config["storage"]["logger_name"] = "lcm.fs" + config["message"]["logger_name"] = "lcm.msg" + if "logfile" in config["global"]: + file_handler = logging.handlers.RotatingFileHandler(config["global"]["logfile"], + maxBytes=100e6, backupCount=9, delay=0) + file_handler.setFormatter(log_formatter_simple) + self.logger.addHandler(file_handler) + else: + str_handler = logging.StreamHandler() + str_handler.setFormatter(log_formatter_simple) + self.logger.addHandler(str_handler) + + if config["global"].get("loglevel"): + self.logger.setLevel(config["global"]["loglevel"]) + + # logging other modules + for k1, logname in {"message": "lcm.msg", "database": "lcm.db", "storage": "lcm.fs"}.items(): + config[k1]["logger_name"] = logname + logger_module = logging.getLogger(logname) + if "logfile" in config[k1]: + file_handler = logging.handlers.RotatingFileHandler(config[k1]["logfile"], + maxBytes=100e6, backupCount=9, delay=0) + file_handler.setFormatter(log_formatter_simple) + logger_module.addHandler(file_handler) + if "loglevel" in config[k1]: + logger_module.setLevel(config[k1]["loglevel"]) + + self.n2vc = N2VC( + log=self.logger, + server=config['VCA']['host'], + port=config['VCA']['port'], + user=config['VCA']['user'], + secret=config['VCA']['secret'], + # TODO: This should point to the base folder where charms are stored, + # if there is a common one (like object storage). Otherwise, leave + # it unset and pass it via DeployCharms + # artifacts=config['VCA'][''], + artifacts=None, + ) + try: if config["database"]["driver"] == "mongo": - self.db = dbmongo.dbmongo() + self.db = dbmongo.DbMongo() self.db.db_connect(config["database"]) elif config["database"]["driver"] == "memory": - self.db = dbmemory.dbmemory() + self.db = dbmemory.DbMemory() self.db.db_connect(config["database"]) else: raise LcmException("Invalid configuration param '{}' at '[database]':'driver'".format( @@ -63,7 +124,7 @@ class Lcm: config["storage"]["driver"])) if config["message"]["driver"] == "local": - self.msg = msglocal.msgLocal() + self.msg = msglocal.MsgLocal() self.msg.connect(config["message"]) elif config["message"]["driver"] == "kafka": self.msg = msgkafka.MsgKafka() @@ -75,92 +136,524 @@ class Lcm: self.logger.critical(str(e), exc_info=True) raise LcmException(str(e)) - # def update_nsr_db(self, nsr_id, nsr_desc): - # self.db.replace("nsrs", nsr_id, nsr_desc) - - async def create_ns(self, nsr_id): - self.logger.debug("create_ns task nsr_id={} Enter".format(nsr_id)) - db_nsr = self.db.get_one("nsrs", {"_id": nsr_id}) - nsr_lcm = { - "id": nsr_id, - "RO": {"vnfd_id": {}, "nsd_id": None, "nsr_id": None, "nsr_status": "SCHEDULED"}, - "nsr_ip": {}, - "VCA": {}, # "TODO" - } - db_nsr["_admin"]["deploy"] = nsr_lcm - db_nsr["detailed-status"] = "creating" - db_nsr["operational-status"] = "init" + def update_db(self, item, _id, _desc): + try: + self.db.replace(item, _id, _desc) + except DbException as e: + self.logger.error("Updating {} _id={}: {}".format(item, _id, e)) - deloyment_timeout = 120 + async def create_vim(self, vim_content, order_id): + vim_id = vim_content["_id"] + logging_text = "Task create_vim={} ".format(vim_id) + self.logger.debug(logging_text + "Enter") + db_vim = None + exc = None try: - nsd = db_nsr["nsd"] - RO = ROclient.ROClient(self.loop, endpoint_url=self.ro_url, tenant=self.ro_tenant, - datacenter=db_nsr["datacenter"]) + step = "Getting vim from db" + db_vim = self.db.get_one("vims", {"_id": vim_id}) + if "_admin" not in db_vim: + db_vim["_admin"] = {} + if "deploy" not in db_vim["_admin"]: + db_vim["_admin"]["deploy"] = {} + db_vim["_admin"]["deploy"]["RO"] = None - # get vnfds, instantiate at RO + step = "Creating vim at RO" + RO = ROclient.ROClient(self.loop, **self.ro_config) + vim_RO = deepcopy(vim_content) + vim_RO.pop("_id", None) + vim_RO.pop("_admin", None) + vim_RO.pop("schema_version", None) + vim_RO.pop("schema_type", None) + vim_RO.pop("vim_tenant_name", None) + vim_RO["type"] = vim_RO.pop("vim_type") + vim_RO.pop("vim_user", None) + vim_RO.pop("vim_password", None) + desc = await RO.create("vim", descriptor=vim_RO) + RO_vim_id = desc["uuid"] + db_vim["_admin"]["deploy"]["RO"] = RO_vim_id + self.update_db("vims", vim_id, db_vim) + + step = "Attach vim to RO tenant" + vim_RO = {"vim_tenant_name": vim_content["vim_tenant_name"], + "vim_username": vim_content["vim_user"], + "vim_password": vim_content["vim_password"], + "config": vim_content["config"] + } + desc = await RO.attach_datacenter(RO_vim_id , descriptor=vim_RO) + db_vim["_admin"]["operationalState"] = "ENABLED" + self.update_db("vims", vim_id, db_vim) + + self.logger.debug(logging_text + "Exit Ok RO_vim_id".format(RO_vim_id)) + return RO_vim_id + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_vim: + db_vim["_admin"]["operationalState"] = "ERROR" + db_vim["_admin"]["detailed-status"] = "ERROR {}: {}".format(step , exc) + self.update_db("vims", vim_id, db_vim) + + async def edit_vim(self, vim_content, order_id): + vim_id = vim_content["_id"] + logging_text = "Task edit_vim={} ".format(vim_id) + self.logger.debug(logging_text + "Enter") + db_vim = None + exc = None + step = "Getting vim from db" + try: + db_vim = self.db.get_one("vims", {"_id": vim_id}) + if db_vim.get("_admin") and db_vim["_admin"].get("deploy") and db_vim["_admin"]["deploy"].get("RO"): + RO_vim_id = db_vim["_admin"]["deploy"]["RO"] + step = "Editing vim at RO" + RO = ROclient.ROClient(self.loop, **self.ro_config) + vim_RO = deepcopy(vim_content) + vim_RO.pop("_id", None) + vim_RO.pop("_admin", None) + vim_RO.pop("schema_version", None) + vim_RO.pop("schema_type", None) + vim_RO.pop("vim_tenant_name", None) + vim_RO["type"] = vim_RO.pop("vim_type") + vim_RO.pop("vim_user", None) + vim_RO.pop("vim_password", None) + if vim_RO: + desc = await RO.edit("vim", RO_vim_id, descriptor=vim_RO) + + step = "Editing vim-account at RO tenant" + vim_RO = {} + for k in ("vim_tenant_name", "vim_password", "config"): + if k in vim_content: + vim_RO[k] = vim_content[k] + if "vim_user" in vim_content: + vim_content["vim_username"] = vim_content["vim_user"] + if vim_RO: + desc = await RO.edit("vim_account", RO_vim_id, descriptor=vim_RO) + db_vim["_admin"]["operationalState"] = "ENABLED" + self.update_db("vims", vim_id, db_vim) + + self.logger.debug(logging_text + "Exit Ok RO_vim_id".format(RO_vim_id)) + return RO_vim_id + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_vim: + db_vim["_admin"]["operationalState"] = "ERROR" + db_vim["_admin"]["detailed-status"] = "ERROR {}: {}".format(step , exc) + self.update_db("vims", vim_id, db_vim) + + async def delete_vim(self, vim_id, order_id): + logging_text = "Task delete_vim={} ".format(vim_id) + self.logger.debug(logging_text + "Enter") + db_vim = None + exc = None + step = "Getting vim from db" + try: + db_vim = self.db.get_one("vims", {"_id": vim_id}) + if db_vim.get("_admin") and db_vim["_admin"].get("deploy") and db_vim["_admin"]["deploy"].get("RO"): + RO_vim_id = db_vim["_admin"]["deploy"]["RO"] + RO = ROclient.ROClient(self.loop, **self.ro_config) + step = "Detaching vim from RO tenant" + try: + await RO.detach_datacenter(RO_vim_id) + except ROclient.ROClientException as e: + if e.http_code == 404: # not found + self.logger.debug(logging_text + "RO_vim_id={} already detached".format(RO_vim_id)) + else: + raise + + step = "Deleting vim from RO" + try: + await RO.delete("vim", RO_vim_id) + except ROclient.ROClientException as e: + if e.http_code == 404: # not found + self.logger.debug(logging_text + "RO_vim_id={} already deleted".format(RO_vim_id)) + else: + raise + else: + # nothing to delete + self.logger.error(logging_text + "Skipping. There is not RO information at database") + self.db.del_one("vims", {"_id": vim_id}) + self.logger.debug("delete_vim task vim_id={} Exit Ok".format(vim_id)) + return None + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_vim: + db_vim["_admin"]["operationalState"] = "ERROR" + db_vim["_admin"]["detailed-status"] = "ERROR {}: {}".format(step , exc) + self.update_db("vims", vim_id, db_vim) + + async def create_sdn(self, sdn_content, order_id): + sdn_id = sdn_content["_id"] + logging_text = "Task create_sdn={} ".format(sdn_id) + self.logger.debug(logging_text + "Enter") + db_sdn = None + exc = None + try: + step = "Getting sdn from db" + db_sdn = self.db.get_one("sdns", {"_id": sdn_id}) + if "_admin" not in db_sdn: + db_sdn["_admin"] = {} + if "deploy" not in db_sdn["_admin"]: + db_sdn["_admin"]["deploy"] = {} + db_sdn["_admin"]["deploy"]["RO"] = None + + step = "Creating sdn at RO" + RO = ROclient.ROClient(self.loop, **self.ro_config) + sdn_RO = deepcopy(sdn_content) + sdn_RO.pop("_id", None) + sdn_RO.pop("_admin", None) + sdn_RO.pop("schema_version", None) + sdn_RO.pop("schema_type", None) + desc = await RO.create("sdn", descriptor=sdn_RO) + RO_sdn_id = desc["uuid"] + db_sdn["_admin"]["deploy"]["RO"] = RO_sdn_id + db_sdn["_admin"]["operationalState"] = "ENABLED" + self.update_db("sdns", sdn_id, db_sdn) + self.logger.debug(logging_text + "Exit Ok RO_sdn_id".format(RO_sdn_id)) + return RO_sdn_id + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_sdn: + db_sdn["_admin"]["operationalState"] = "ERROR" + db_sdn["_admin"]["detailed-status"] = "ERROR {}: {}".format(step , exc) + self.update_db("sdns", sdn_id, db_sdn) + + async def edit_sdn(self, sdn_content, order_id): + sdn_id = sdn_content["_id"] + logging_text = "Task edit_sdn={} ".format(sdn_id) + self.logger.debug(logging_text + "Enter") + db_sdn = None + exc = None + step = "Getting sdn from db" + try: + db_sdn = self.db.get_one("sdns", {"_id": sdn_id}) + if db_sdn.get("_admin") and db_sdn["_admin"].get("deploy") and db_sdn["_admin"]["deploy"].get("RO"): + RO_sdn_id = db_sdn["_admin"]["deploy"]["RO"] + RO = ROclient.ROClient(self.loop, **self.ro_config) + step = "Editing sdn at RO" + sdn_RO = deepcopy(sdn_content) + sdn_RO.pop("_id", None) + sdn_RO.pop("_admin", None) + sdn_RO.pop("schema_version", None) + sdn_RO.pop("schema_type", None) + if sdn_RO: + desc = await RO.edit("sdn", RO_sdn_id, descriptor=sdn_RO) + db_sdn["_admin"]["operationalState"] = "ENABLED" + self.update_db("sdns", sdn_id, db_sdn) + + self.logger.debug(logging_text + "Exit Ok RO_sdn_id".format(RO_sdn_id)) + return RO_sdn_id + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_sdn: + db_sdn["_admin"]["operationalState"] = "ERROR" + db_sdn["_admin"]["detailed-status"] = "ERROR {}: {}".format(step , exc) + self.update_db("sdns", sdn_id, db_sdn) + + async def delete_sdn(self, sdn_id, order_id): + logging_text = "Task delete_sdn={} ".format(sdn_id) + self.logger.debug(logging_text + "Enter") + db_sdn = None + exc = None + step = "Getting sdn from db" + try: + db_sdn = self.db.get_one("sdns", {"_id": sdn_id}) + if db_sdn.get("_admin") and db_sdn["_admin"].get("deploy") and db_sdn["_admin"]["deploy"].get("RO"): + RO_sdn_id = db_sdn["_admin"]["deploy"]["RO"] + RO = ROclient.ROClient(self.loop, **self.ro_config) + step = "Deleting sdn from RO" + try: + await RO.delete("sdn", RO_sdn_id) + except ROclient.ROClientException as e: + if e.http_code == 404: # not found + self.logger.debug(logging_text + "RO_sdn_id={} already deleted".format(RO_sdn_id)) + else: + raise + else: + # nothing to delete + self.logger.error(logging_text + "Skipping. There is not RO information at database") + self.db.del_one("sdns", {"_id": sdn_id}) + self.logger.debug("delete_sdn task sdn_id={} Exit Ok".format(sdn_id)) + return None + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_sdn: + db_sdn["_admin"]["operationalState"] = "ERROR" + db_sdn["_admin"]["detailed-status"] = "ERROR {}: {}".format(step , exc) + self.update_db("sdns", sdn_id, db_sdn) + + def vnfd2RO(self, vnfd, new_id=None): + """ + Converts creates a new vnfd descriptor for RO base on input OSM IM vnfd + :param vnfd: input vnfd + :param new_id: overrides vnf id if provided + :return: copy of vnfd + """ + ci_file = None + try: + vnfd_RO = deepcopy(vnfd) + vnfd_RO.pop("_id", None) + vnfd_RO.pop("_admin", None) + if new_id: + vnfd_RO["id"] = new_id + for vdu in vnfd_RO["vdu"]: + if "cloud-init-file" in vdu: + base_folder = vnfd["_admin"]["storage"] + clout_init_file = "{}/{}/cloud_init/{}".format( + base_folder["folder"], + base_folder["pkg-dir"], + vdu["cloud-init-file"] + ) + ci_file = self.fs.file_open(clout_init_file, "r") + # TODO: detect if binary or text. Propose to read as binary and try to decode to utf8. If fails convert to base 64 or similar + clout_init_content = ci_file.read() + ci_file.close() + ci_file = None + vdu.pop("cloud-init-file", None) + vdu["cloud-init"] = clout_init_content + return vnfd_RO + except FsException as e: + raise LcmException("Error reading file at vnfd {}: {} ".format(vnfd["_id"], e)) + finally: + if ci_file: + ci_file.close() + + def n2vc_callback(self, model_name, application_name, workload_status, db_nsr, vnf_member_index, task=None): + """Update the lcm database with the status of the charm. + + Updates the VNF's operational status with the state of the charm: + - blocked: The unit needs manual intervention + - maintenance: The unit is actively deploying/configuring + - waiting: The unit is waiting for another charm to be ready + - active: The unit is deployed, configured, and ready + - error: The charm has failed and needs attention. + - terminated: The charm has been destroyed + - removing, + - removed + + Updates the network service's config-status to reflect the state of all + charms. + """ + nsr_id = None + try: + nsr_id = db_nsr["_id"] + nsr_lcm = db_nsr["_admin"]["deploy"] + if task: + if task.cancelled(): + self.logger.debug("[n2vc_callback] create_ns={} vnf_index={} task Cancelled".format(nsr_id, vnf_member_index)) + return + + if task.done(): + exc = task.exception() + if exc: + self.logger.error( + "[n2vc_callback] create_ns={} vnf_index={} task Exception={}".format(nsr_id, vnf_member_index, exc)) + nsr_lcm["VCA"][vnf_member_index]['operational-status'] = "error" + nsr_lcm["VCA"][vnf_member_index]['detailed-status'] = str(exc) + else: + self.logger.debug("[n2vc_callback] create_ns={} vnf_index={} task Done".format(nsr_id, vnf_member_index)) + # TODO it seams that task Done, but callback is still ongoing. For the moment comment this two lines + # nsr_lcm["VCA"][vnf_member_index]['operational-status'] = "active" + # nsr_lcm["VCA"][vnf_member_index]['detailed-status'] = "" + elif workload_status: + self.logger.debug("[n2vc_callback] create_ns={} vnf_index={} Enter workload_status={}".format(nsr_id, vnf_member_index, workload_status)) + if nsr_lcm["VCA"][vnf_member_index]['operational-status'] == workload_status: + return # same status, ignore + nsr_lcm["VCA"][vnf_member_index]['operational-status'] = workload_status + # TODO N2VC some error message in case of error should be obtained from N2VC + nsr_lcm["VCA"][vnf_member_index]['detailed-status'] = "" + else: + self.logger.critical("[n2vc_callback] create_ns={} vnf_index={} Enter with bad parameters".format(nsr_id, vnf_member_index), exc_info=True) + return + + some_failed = False + all_active = True + status_map = {} + for vnf_index, vca_info in nsr_lcm["VCA"].items(): + vca_status = vca_info["operational-status"] + if vca_status not in status_map: + # Initialize it + status_map[vca_status] = 0 + status_map[vca_status] += 1 + + if vca_status != "active": + all_active = False + if vca_status == "error": + some_failed = True + db_nsr["config-status"] = "failed" + db_nsr["detailed-status"] = "fail configuring vnf_index={} {}".format(vnf_member_index, + vca_info["detailed-status"]) + break + + if all_active: + self.logger.debug("[n2vc_callback] create_ns={} vnf_index={} All active".format(nsr_id, vnf_member_index)) + db_nsr["config-status"] = "configured" + db_nsr["detailed-status"] = "done" + elif some_failed: + pass + else: + cs = "configuring: " + separator = "" + for status, num in status_map.items(): + cs += separator + "{}: {}".format(status, num) + separator = ", " + db_nsr["config-status"] = cs + self.update_db("nsrs", nsr_id, db_nsr) + + except Exception as e: + self.logger.critical("[n2vc_callback] create_ns={} vnf_index={} Exception {}".format(nsr_id, vnf_member_index, e), exc_info=True) + + async def create_ns(self, nsr_id, order_id): + logging_text = "Task create_ns={} ".format(nsr_id) + self.logger.debug(logging_text + "Enter") + # get all needed from database + db_nsr = None + exc = None + step = "Getting nsr from db" + try: + db_nsr = self.db.get_one("nsrs", {"_id": nsr_id}) + nsd = db_nsr["nsd"] + nsr_name = db_nsr["name"] # TODO short-name?? + needed_vnfd = {} for c_vnf in nsd["constituent-vnfd"]: vnfd_id = c_vnf["vnfd-id-ref"] - self.logger.debug("create_ns task nsr_id={} RO vnfd={} creating".format(nsr_id, vnfd_id)) - db_nsr["detailed-status"] = "Creating vnfd {} at RO".format(vnfd_id) - vnfd = self.db.get_one("vnfds", {"id": vnfd_id}) - vnfd.pop("_admin", None) - vnfd.pop("_id", None) + if vnfd_id not in needed_vnfd: + step = "Getting vnfd={} from db".format(vnfd_id) + needed_vnfd[vnfd_id] = self.db.get_one("vnfds", {"id": vnfd_id}) + + nsr_lcm = { + "id": nsr_id, + "RO": {"vnfd_id": {}, "nsd_id": None, "nsr_id": None, "nsr_status": "SCHEDULED"}, + "nsr_ip": {}, + "VCA": {}, + } + db_nsr["_admin"]["deploy"] = nsr_lcm + db_nsr["detailed-status"] = "creating" + db_nsr["operational-status"] = "init" + + deloyment_timeout = 120 + + RO = ROclient.ROClient(self.loop, datacenter=db_nsr["datacenter"], **self.ro_config) + + # get vnfds, instantiate at RO + for vnfd_id, vnfd in needed_vnfd.items(): + step = db_nsr["detailed-status"] = "Creating vnfd={} at RO".format(vnfd_id) + self.logger.debug(logging_text + step) + vnfd_id_RO = nsr_id + "." + vnfd_id[:200] # look if present - vnfd_list = await RO.get_list("vnfd", filter_by={"osm_id": vnfd_id}) + vnfd_list = await RO.get_list("vnfd", filter_by={"osm_id": vnfd_id_RO}) if vnfd_list: nsr_lcm["RO"]["vnfd_id"][vnfd_id] = vnfd_list[0]["uuid"] - self.logger.debug("create_ns task nsr_id={} RO vnfd={} exist. Using RO_id={}".format( - nsr_id, vnfd_id, vnfd_list[0]["uuid"])) + self.logger.debug(logging_text + "RO vnfd={} exist. Using RO_id={}".format( + vnfd_id, vnfd_list[0]["uuid"])) else: - desc = await RO.create("vnfd", descriptor=vnfd) + vnfd_RO = self.vnfd2RO(vnfd, vnfd_id_RO) + desc = await RO.create("vnfd", descriptor=vnfd_RO) nsr_lcm["RO"]["vnfd_id"][vnfd_id] = desc["uuid"] - self.db.replace("nsrs", nsr_id, db_nsr) - - # db_new("vnfr", vnfr) - # db_update("ns_request", nsr_id, ns_request) + self.update_db("nsrs", nsr_id, db_nsr) # create nsd at RO - nsd_id = db_nsr["nsd"]["id"] - self.logger.debug("create_ns task nsr_id={} RO nsd={} creating".format(nsr_id, nsd_id)) - db_nsr["detailed-status"] = "Creating nsd {} at RO".format(nsd_id) - nsd = self.db.get_one("nsds", {"id": nsd_id}) - nsd.pop("_admin", None) - nsd.pop("_id", None) - - nsd_list = await RO.get_list("nsd", filter_by={"osm_id": nsd_id}) + nsd_id = nsd["id"] + step = db_nsr["detailed-status"] = "Creating nsd={} at RO".format(nsd_id) + self.logger.debug(logging_text + step) + + nsd_id_RO = nsd_id + "." + nsd_id[:200] + nsd_list = await RO.get_list("nsd", filter_by={"osm_id": nsd_id_RO}) if nsd_list: nsr_lcm["RO"]["nsd_id"] = nsd_list[0]["uuid"] - self.logger.debug("create_ns task nsr_id={} RO nsd={} exist. Using RO_id={}".format( - nsr_id, nsd_id, nsd_list[0]["uuid"])) + self.logger.debug(logging_text + "RO nsd={} exist. Using RO_id={}".format( + nsd_id, nsd_list[0]["uuid"])) else: - desc = await RO.create("nsd", descriptor=nsd) + nsd_RO = deepcopy(nsd) + nsd_RO["id"] = nsd_id_RO + nsd_RO.pop("_id", None) + nsd_RO.pop("_admin", None) + for c_vnf in nsd_RO["constituent-vnfd"]: + vnfd_id = c_vnf["vnfd-id-ref"] + c_vnf["vnfd-id-ref"] = nsr_id + "." + vnfd_id[:200] + desc = await RO.create("nsd", descriptor=nsd_RO) nsr_lcm["RO"]["nsd_id"] = desc["uuid"] - self.db.replace("nsrs", nsr_id, db_nsr) + self.update_db("nsrs", nsr_id, db_nsr) # Crate ns at RO - self.logger.debug("create_ns task nsr_id={} RO ns creating".format(nsr_id)) - db_nsr["detailed-status"] = "Creating ns at RO" - desc = await RO.create("ns", name=db_nsr["name"], datacenter=db_nsr["datacenter"], - scenario=nsr_lcm["RO"]["nsd_id"]) - RO_nsr_id = desc["uuid"] - nsr_lcm["RO"]["nsr_id"] = RO_nsr_id - nsr_lcm["RO"]["nsr_status"] = "BUILD" - self.db.replace("nsrs", nsr_id, db_nsr) + # if present use it unless in error status + RO_nsr_id = nsr_lcm["RO"].get("nsr_id") + if RO_nsr_id: + try: + step = db_nsr["detailed-status"] = "Looking for existing ns at RO" + self.logger.debug(logging_text + step + " RO_ns_id={}".format(RO_nsr_id)) + desc = await RO.show("ns", RO_nsr_id) + except ROclient.ROClientException as e: + if e.http_code != HTTPStatus.NOT_FOUND: + raise + RO_nsr_id = nsr_lcm["RO"]["nsr_id"] = None + if RO_nsr_id: + ns_status, ns_status_info = RO.check_ns_status(desc) + nsr_lcm["RO"]["nsr_status"] = ns_status + if ns_status == "ERROR": + step = db_nsr["detailed-status"] = "Deleting ns at RO" + self.logger.debug(logging_text + step + " RO_ns_id={}".format(RO_nsr_id)) + await RO.delete("ns", RO_nsr_id) + RO_nsr_id = nsr_lcm["RO"]["nsr_id"] = None + + if not RO_nsr_id: + step = db_nsr["detailed-status"] = "Creating ns at RO" + self.logger.debug(logging_text + step) + + desc = await RO.create("ns", name=db_nsr["name"], datacenter=db_nsr["datacenter"], + scenario=nsr_lcm["RO"]["nsd_id"]) + RO_nsr_id = nsr_lcm["RO"]["nsr_id"] = desc["uuid"] + nsr_lcm["RO"]["nsr_status"] = "BUILD" + self.update_db("nsrs", nsr_id, db_nsr) # wait until NS is ready - self.logger.debug("create_ns task nsr_id={} RO ns_id={} waiting to be ready".format(nsr_id, RO_nsr_id)) + step = ns_status_detailed = "Waiting ns ready at RO" + db_nsr["detailed-status"] = ns_status_detailed + self.logger.debug(logging_text + step + " RO_ns_id={}".format(RO_nsr_id)) deloyment_timeout = 600 while deloyment_timeout > 0: - ns_status_detailed = "Waiting ns ready at RO" - db_nsr["detailed-status"] = ns_status_detailed desc = await RO.show("ns", RO_nsr_id) ns_status, ns_status_info = RO.check_ns_status(desc) nsr_lcm["RO"]["nsr_status"] = ns_status if ns_status == "ERROR": raise ROclient.ROClientException(ns_status_info) elif ns_status == "BUILD": - db_nsr["detailed-status"] = ns_status_detailed + "; nsr_id: '{}', {}".format(nsr_id, ns_status_info) + db_nsr["detailed-status"] = ns_status_detailed + "; {}".format(ns_status_info) + self.update_db("nsrs", nsr_id, db_nsr) elif ns_status == "ACTIVE": nsr_lcm["nsr_ip"] = RO.get_ns_vnf_ip(desc) break @@ -170,224 +663,409 @@ class Lcm: await asyncio.sleep(5, loop=self.loop) deloyment_timeout -= 5 if deloyment_timeout <= 0: - raise ROclient.ROClientException("Timeot wating ns to be ready") + raise ROclient.ROClientException("Timeout waiting ns to be ready") db_nsr["detailed-status"] = "Configuring vnfr" - self.db.replace("nsrs", nsr_id, db_nsr) + self.update_db("nsrs", nsr_id, db_nsr) + + # The parameters we'll need to deploy a charm + number_to_configure = 0 + + def deploy(): + """An inner function to deploy the charm from either vnf or vdu + """ + + # Login to the VCA. + # if number_to_configure == 0: + # self.logger.debug("Logging into N2VC...") + # task = asyncio.ensure_future(self.n2vc.login()) + # yield from asyncio.wait_for(task, 30.0) + # self.logger.debug("Logged into N2VC!") + + ## await self.n2vc.login() + + # Note: The charm needs to exist on disk at the location + # specified by charm_path. + base_folder = vnfd["_admin"]["storage"] + storage_params = self.fs.get_params() + charm_path = "{}{}/{}/charms/{}".format( + storage_params["path"], + base_folder["folder"], + base_folder["pkg-dir"], + proxy_charm + ) + + # Setup the runtime parameters for this VNF + params['rw_mgmt_ip'] = nsr_lcm['nsr_ip'][vnf_index] - #for nsd in nsr_lcm["descriptors"]["nsd"]: + # ns_name will be ignored in the current version of N2VC + # but will be implemented for the next point release. + model_name = 'default' + application_name = self.n2vc.FormatApplicationName( + nsr_name, + vnf_index, + vnfd['name'], + ) - self.logger.debug("create_ns task nsr_id={} VCA look for".format(nsr_id)) + nsr_lcm["VCA"][vnf_index] = { + "model": model_name, + "application": application_name, + "operational-status": "init", + "detailed-status": "", + "vnfd_id": vnfd_id, + } + + self.logger.debug("Task create_ns={} Passing artifacts path '{}' for {}".format(nsr_id, charm_path, proxy_charm)) + task = asyncio.ensure_future( + self.n2vc.DeployCharms( + model_name, # The network service name + application_name, # The application name + vnfd, # The vnf descriptor + charm_path, # Path to charm + params, # Runtime params, like mgmt ip + {}, # for native charms only + self.n2vc_callback, # Callback for status changes + db_nsr, # Callback parameter + vnf_index, # Callback parameter + None, # Callback parameter (task) + ) + ) + task.add_done_callback(functools.partial(self.n2vc_callback, model_name, application_name, None, db_nsr, vnf_index)) + self.lcm_ns_tasks[nsr_id][order_id]["create_charm:" + vnf_index] = task + + # TODO: Make this call inside deploy() + # Login to the VCA. If there are multiple calls to login(), + # subsequent calls will be a nop and return immediately. + await self.n2vc.login() + + step = "Looking for needed vnfd to configure" + self.logger.debug(logging_text + step) for c_vnf in nsd["constituent-vnfd"]: vnfd_id = c_vnf["vnfd-id-ref"] - vnfd_index = str(c_vnf["member-vnf-index"]) - vnfd = self.db.get_one("vnfds", {"id": vnfd_id}) - db_nsr["config-status"] = "config_not_needed" - if vnfd.get("vnf-configuration") and vnfd["vnf-configuration"].get("juju"): - db_nsr["config-status"] = "configuring" - proxy_charm = vnfd["vnf-configuration"]["juju"]["charm"] - - # Note: The charm needs to exist on disk at the location - # specified by charm_path. - base_folder = vnfd["_admin"]["storage"] - charm_path = "{}{}/{}/charms/{}".format( - base_folder["path"], - base_folder["folder"], - base_folder["file"], - proxy_charm - ) - task = asyncio.ensure_future( - DeployApplication( - self.config['VCA'], - self.db, - db_nsr, - vnfd, - vnfd_index, - charm_path, - ) - ) + vnf_index = str(c_vnf["member-vnf-index"]) + vnfd = needed_vnfd[vnfd_id] - db_nsr["detailed-status"] = "Done" - db_nsr["operational-status"] = "running" - self.db.replace("nsrs", nsr_id, db_nsr) + # Check if this VNF has a charm configuration + vnf_config = vnfd.get("vnf-configuration") - self.logger.debug("create_ns task nsr_id={} Exit Ok".format(nsr_id)) - return nsr_lcm + if vnf_config and vnf_config.get("juju"): + proxy_charm = vnf_config["juju"]["charm"] + params = {} + + if proxy_charm: + if 'initial-config-primitive' in vnf_config: + params['initial-config-primitive'] = vnf_config['initial-config-primitive'] + + deploy() + number_to_configure += 1 + + # Deploy charms for each VDU that supports one. + for vdu in vnfd['vdu']: + vdu_config = vdu.get('vdu-configuration') + proxy_charm = None + params = {} - except (ROclient.ROClientException, Exception) as e: - db_nsr["operational-status"] = "failed" - db_nsr["detailed-status"] += ": ERROR {}".format(e) - self.db.replace("nsrs", nsr_id, db_nsr) - self.logger.debug( - "create_ns nsr_id={} Exit Exception on '{}': {}".format(nsr_id, db_nsr["detailed-status"], e), - exc_info=True) + if vdu_config and vdu_config.get("juju"): + proxy_charm = vdu_config["juju"]["charm"] + + if 'initial-config-primitive' in vdu_config: + params['initial-config-primitive'] = vdu_config['initial-config-primitive'] + + if proxy_charm: + deploy() + number_to_configure += 1 + + db_nsr["config-status"] = "configuring" if number_to_configure else "configured" + db_nsr["detailed-status"] = "configuring: init: {}".format(number_to_configure) if number_to_configure else "done" + db_nsr["operational-status"] = "running" + self.update_db("nsrs", nsr_id, db_nsr) - async def delete_ns(self, nsr_id): - self.logger.debug("delete_ns task nsr_id={}, Delete_ns task nsr_id={} Enter".format(nsr_id, nsr_id)) - db_nsr = self.db.get_one("nsrs", {"_id": nsr_id}) - nsr_lcm = db_nsr["_admin"]["deploy"] + self.logger.debug("Task create_ns={} Exit Ok".format(nsr_id)) + return nsr_lcm - db_nsr["operational-status"] = "terminate" - db_nsr["config-status"] = "terminate" - db_nsr["detailed-status"] = "Deleting charms" - self.db.replace("nsrs", nsr_id, db_nsr) + except (ROclient.ROClientException, DbException, LcmException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_nsr: + db_nsr["detailed-status"] = "ERROR {}: {}".format(step , exc) + db_nsr["operational-status"] = "failed" + self.update_db("nsrs", nsr_id, db_nsr) + async def delete_ns(self, nsr_id, order_id): + logging_text = "Task delete_ns={} ".format(nsr_id) + self.logger.debug(logging_text + "Enter") + db_nsr = None + exc = None + step = "Getting nsr from db" try: - self.logger.debug("Deleting charms") + db_nsr = self.db.get_one("nsrs", {"_id": nsr_id}) nsd = db_nsr["nsd"] - for c_vnf in nsd["constituent-vnfd"]: - vnfd_id = c_vnf["vnfd-id-ref"] - vnfd_index = str(c_vnf["member-vnf-index"]) - vnfd = self.db.get_one("vnfds", {"id": vnfd_id}) - if vnfd.get("vnf-configuration") and vnfd["vnf-configuration"].get("juju"): - asyncio.ensure_future( - RemoveApplication( - self.config['VCA'], - self.db, - db_nsr, - vnfd, - vnfd_index, - ) - ) - except Exception as e: - self.logger.debug("Failed while deleting charms: {}".format(e)) - # remove from RO - - RO = ROclient.ROClient(self.loop, endpoint_url=self.ro_url, tenant=self.ro_tenant, - datacenter=db_nsr["datacenter"]) - # Delete ns - RO_nsr_id = nsr_lcm["RO"]["nsr_id"] - if RO_nsr_id: + nsr_lcm = db_nsr["_admin"]["deploy"] + + db_nsr["operational-status"] = "terminating" + db_nsr["config-status"] = "terminating" + db_nsr["detailed-status"] = "Deleting charms" + self.update_db("nsrs", nsr_id, db_nsr) + try: - db_nsr["detailed-status"] = "Deleting ns at RO" - desc = await RO.delete("ns", RO_nsr_id) - self.logger.debug("delete_ns task nsr_id={} RO ns={} deleted".format(nsr_id, RO_nsr_id)) - nsr_lcm["RO"]["nsr_id"] = None - nsr_lcm["RO"]["nsr_status"] = "DELETED" - except ROclient.ROClientException as e: - if e.http_code == 404: # not found + self.logger.debug(logging_text + step) + for vnf_index, deploy_info in nsr_lcm["VCA"].items(): + if deploy_info and deploy_info.get("application"): + # n2vc_callback(self, model_name, application_name, workload_status, db_nsr, vnf_member_index, task=None): + + # self.n2vc.RemoveCharms(model_name, application_name, self.n2vc_callback, model_name, application_name) + task = asyncio.ensure_future( + self.n2vc.RemoveCharms( + deploy_info['model'], + deploy_info['application'], + self.n2vc_callback, + db_nsr, + vnf_index, + ) + ) + # task.add_done_callback(functools.partial(self.n2vc_callback, deploy_info['model'], + # deploy_info['application'],None, db_nsr, vnf_index)) + self.lcm_ns_tasks[nsr_id][order_id]["delete_charm:" + vnf_index] = task + except Exception as e: + self.logger.debug(logging_text + "Failed while deleting charms: {}".format(e)) + # remove from RO + + RO = ROclient.ROClient(self.loop, datacenter=db_nsr["datacenter"], **self.ro_config) + # Delete ns + RO_nsr_id = nsr_lcm["RO"]["nsr_id"] + if RO_nsr_id: + try: + step = db_nsr["detailed-status"] = "Deleting ns at RO" + self.logger.debug(logging_text + step) + desc = await RO.delete("ns", RO_nsr_id) nsr_lcm["RO"]["nsr_id"] = None nsr_lcm["RO"]["nsr_status"] = "DELETED" - self.logger.debug("delete_ns task nsr_id={} RO ns={} already deleted".format(nsr_id, RO_nsr_id)) - elif e.http_code == 409: #conflict - self.logger.debug("delete_ns task nsr_id={} RO ns={} delete conflict: {}".format(nsr_id, RO_nsr_id, - e)) - else: - self.logger.error("delete_ns task nsr_id={} RO ns={} delete error: {}".format(nsr_id, RO_nsr_id, e)) - self.db.replace("nsrs", nsr_id, db_nsr) + except ROclient.ROClientException as e: + if e.http_code == 404: # not found + nsr_lcm["RO"]["nsr_id"] = None + nsr_lcm["RO"]["nsr_status"] = "DELETED" + self.logger.debug(logging_text + "RO_ns_id={} already deleted".format(RO_nsr_id)) + elif e.http_code == 409: #conflict + self.logger.debug(logging_text + "RO_ns_id={} delete conflict: {}".format(RO_nsr_id, e)) + else: + self.logger.error(logging_text + "RO_ns_id={} delete error: {}".format(RO_nsr_id, e)) + self.update_db("nsrs", nsr_id, db_nsr) - # Delete nsd - RO_nsd_id = nsr_lcm["RO"]["nsd_id"] - if RO_nsd_id: - try: - db_nsr["detailed-status"] = "Deleting nsd at RO" - desc = await RO.delete("nsd", RO_nsd_id) - self.logger.debug("delete_ns task nsr_id={} RO nsd={} deleted".format(nsr_id, RO_nsd_id)) - nsr_lcm["RO"]["nsd_id"] = None - except ROclient.ROClientException as e: - if e.http_code == 404: # not found + # Delete nsd + RO_nsd_id = nsr_lcm["RO"]["nsd_id"] + if RO_nsd_id: + try: + step = db_nsr["detailed-status"] = "Deleting nsd at RO" + desc = await RO.delete("nsd", RO_nsd_id) + self.logger.debug(logging_text + "RO_nsd_id={} deleted".format(RO_nsd_id)) nsr_lcm["RO"]["nsd_id"] = None - self.logger.debug("delete_ns task nsr_id={} RO nsd={} already deleted".format(nsr_id, RO_nsd_id)) - elif e.http_code == 409: #conflict - self.logger.debug("delete_ns task nsr_id={} RO nsd={} delete conflict: {}".format(nsr_id, RO_nsd_id, - e)) - else: - self.logger.error("delete_ns task nsr_id={} RO nsd={} delete error: {}".format(nsr_id, RO_nsd_id, - e)) - self.db.replace("nsrs", nsr_id, db_nsr) + except ROclient.ROClientException as e: + if e.http_code == 404: # not found + nsr_lcm["RO"]["nsd_id"] = None + self.logger.debug(logging_text + "RO_nsd_id={} already deleted".format(RO_nsd_id)) + elif e.http_code == 409: #conflict + self.logger.debug(logging_text + "RO_nsd_id={} delete conflict: {}".format(RO_nsd_id, e)) + else: + self.logger.error(logging_text + "RO_nsd_id={} delete error: {}".format(RO_nsd_id, e)) + self.update_db("nsrs", nsr_id, db_nsr) - for vnf_id, RO_vnfd_id in nsr_lcm["RO"]["vnfd_id"].items(): - if not RO_vnfd_id: - continue - try: - db_nsr["detailed-status"] = "Deleting vnfd {} at RO".format(vnf_id) - desc = await RO.delete("vnfd", RO_vnfd_id) - self.logger.debug("delete_ns task nsr_id={} RO vnfd={} deleted".format(nsr_id, RO_vnfd_id)) - nsr_lcm["RO"]["vnfd_id"][vnf_id] = None - except ROclient.ROClientException as e: - if e.http_code == 404: # not found + for vnf_id, RO_vnfd_id in nsr_lcm["RO"]["vnfd_id"].items(): + if not RO_vnfd_id: + continue + try: + step = db_nsr["detailed-status"] = "Deleting vnfd={} at RO".format(vnf_id) + desc = await RO.delete("vnfd", RO_vnfd_id) + self.logger.debug(logging_text + "RO_vnfd_id={} deleted".format(RO_vnfd_id)) nsr_lcm["RO"]["vnfd_id"][vnf_id] = None - self.logger.debug("delete_ns task nsr_id={} RO vnfd={} already deleted ".format(nsr_id, RO_vnfd_id)) - elif e.http_code == 409: #conflict - self.logger.debug("delete_ns task nsr_id={} RO vnfd={} delete conflict: {}".format( - nsr_id, RO_vnfd_id, e)) - else: - self.logger.error("delete_ns task nsr_id={} RO vnfd={} delete error: {}".format( - nsr_id, RO_vnfd_id, e)) - self.db.replace("nsrs", nsr_id, db_nsr) + except ROclient.ROClientException as e: + if e.http_code == 404: # not found + nsr_lcm["RO"]["vnfd_id"][vnf_id] = None + self.logger.debug(logging_text + "RO_vnfd_id={} already deleted ".format(RO_vnfd_id)) + elif e.http_code == 409: #conflict + self.logger.debug(logging_text + "RO_vnfd_id={} delete conflict: {}".format(RO_vnfd_id, e)) + else: + self.logger.error(logging_text + "RO_vnfd_id={} delete error: {}".format(RO_vnfd_id, e)) + self.update_db("nsrs", nsr_id, db_nsr) - # TODO delete from database or mark as deleted??? - db_nsr["operational-status"] = "terminated" - self.db.del_one("nsrs", {"_id": nsr_id}) - self.logger.debug("delete_ns task nsr_id={} Exit".format(nsr_id)) + # TODO delete from database or mark as deleted??? + db_nsr["operational-status"] = "terminated" + self.db.del_one("nsrs", {"_id": nsr_id}) + self.logger.debug(logging_text + "Exit") + + except (ROclient.ROClientException, DbException) as e: + self.logger.error(logging_text + "Exit Exception {}".format(e)) + exc = e + except Exception as e: + self.logger.critical(logging_text + "Exit Exception {}".format(e), exc_info=True) + exc = e + finally: + if exc and db_nsr: + db_nsr["detailed-status"] = "ERROR {}: {}".format(step , exc) + db_nsr["operational-status"] = "failed" + self.update_db("nsrs", nsr_id, db_nsr) async def test(self, param=None): self.logger.debug("Starting/Ending test task: {}".format(param)) - def cancel_tasks(self, nsr_id): + def cancel_tasks(self, topic, _id): """ - Cancel all active tasks of a concrete nsr identified for nsr_id - :param nsr_id: nsr identity + Cancel all active tasks of a concrete nsr or vim identified for _id + :param topic: can be ns or vim_account + :param _id: nsr or vim identity :return: None, or raises an exception if not possible """ - if not self.lcm_tasks.get(nsr_id): + if topic == "ns": + lcm_tasks = self.lcm_ns_tasks + elif topic== "vim_account": + lcm_tasks = self.lcm_vim_tasks + elif topic== "sdn": + lcm_tasks = self.lcm_sdn_tasks + + if not lcm_tasks.get(_id): return - for order_id, tasks_set in self.lcm_tasks[nsr_id].items(): + for order_id, tasks_set in lcm_tasks[_id].items(): for task_name, task in tasks_set.items(): result = task.cancel() if result: - self.logger.debug("nsr_id={} order_id={} task={} cancelled".format(nsr_id, order_id, task_name)) - self.lcm_tasks[nsr_id] = {} + self.logger.debug("{} _id={} order_id={} task={} cancelled".format(topic, _id, order_id, task_name)) + lcm_tasks[_id] = {} async def read_kafka(self): - self.logger.debug("kafka task Enter") + self.logger.debug("Task Kafka Enter") order_id = 1 # future = asyncio.Future() + consecutive_errors = 0 + while consecutive_errors < 10: + try: + topic, command, params = await self.msg.aioread(("ns", "vim_account", "sdn"), self.loop) + self.logger.debug("Task Kafka receives {} {}: {}".format(topic, command, params)) + consecutive_errors = 0 + order_id += 1 + if command == "exit": + print("Bye!") + break + elif command.startswith("#"): + continue + elif command == "echo": + # just for test + print(params) + sys.stdout.flush() + continue + elif command == "test": + asyncio.Task(self.test(params), loop=self.loop) + continue - while True: - command, params = await self.msg.aioread("ns", self.loop) - order_id += 1 - if command == "exit": - print("Bye!") - break - elif command.startswith("#"): - continue - elif command == "echo": - print(params) - elif command == "test": - asyncio.Task(self.test(params), loop=self.loop) - elif command == "break": - print("put a break in this line of code") - elif command == "create": - nsr_id = params.strip() - self.logger.debug("Deploying NS {}".format(nsr_id)) - task = asyncio.ensure_future(self.create_ns(nsr_id)) - if nsr_id not in self.lcm_tasks: - self.lcm_tasks[nsr_id] = {} - self.lcm_tasks[nsr_id][order_id] = {"create_ns": task} - elif command == "delete": - nsr_id = params.strip() - self.logger.debug("Deleting NS {}".format(nsr_id)) - self.cancel_tasks(nsr_id) - task = asyncio.ensure_future(self.delete_ns(nsr_id)) - if nsr_id not in self.lcm_tasks: - self.lcm_tasks[nsr_id] = {} - self.lcm_tasks[nsr_id][order_id] = {"delete_ns": task} - elif command == "show": - nsr_id = params.strip() - nsr_lcm = self.db.get_one("nsr_lcm", {"id": nsr_id}) - print("nsr_lcm", nsr_lcm) - print("self.lcm_tasks", self.lcm_tasks.get(nsr_id)) - else: - self.logger.debug("unknown command '{}'".format(command)) - print("Usage:\n echo: <>\n create: \n delete: \n show: ") - self.logger.debug("kafka task Exit") - + if topic == "ns": + nsr_id = params.strip() + if command == "create": + # self.logger.debug("Deploying NS {}".format(nsr_id)) + task = asyncio.ensure_future(self.create_ns(nsr_id, order_id)) + if nsr_id not in self.lcm_ns_tasks: + self.lcm_ns_tasks[nsr_id] = {} + self.lcm_ns_tasks[nsr_id][order_id] = {"create_ns": task} + continue + elif command == "delete": + # self.logger.debug("Deleting NS {}".format(nsr_id)) + self.cancel_tasks(topic, nsr_id) + task = asyncio.ensure_future(self.delete_ns(nsr_id, order_id)) + if nsr_id not in self.lcm_ns_tasks: + self.lcm_ns_tasks[nsr_id] = {} + self.lcm_ns_tasks[nsr_id][order_id] = {"delete_ns": task} + continue + elif command == "show": + try: + db_nsr = self.db.get_one("nsrs", {"_id": nsr_id}) + print( + "nsr:\n _id={}\n operational-status: {}\n config-status: {}\n detailed-status: " + "{}\n deploy: {}\n tasks: {}".format( + nsr_id, db_nsr["operational-status"], + db_nsr["config-status"], db_nsr["detailed-status"], + db_nsr["_admin"]["deploy"], self.lcm_ns_tasks.get(nsr_id))) + except Exception as e: + print("nsr {} not found: {}".format(nsr_id, e)) + sys.stdout.flush() + continue + elif topic == "vim_account": + vim_id = params["_id"] + if command == "create": + task = asyncio.ensure_future(self.create_vim(params, order_id)) + if vim_id not in self.lcm_vim_tasks: + self.lcm_vim_tasks[vim_id] = {} + self.lcm_vim_tasks[vim_id][order_id] = {"create_vim": task} + continue + elif command == "delete": + self.cancel_tasks(topic, vim_id) + task = asyncio.ensure_future(self.delete_vim(vim_id, order_id)) + if vim_id not in self.lcm_vim_tasks: + self.lcm_vim_tasks[vim_id] = {} + self.lcm_vim_tasks[vim_id][order_id] = {"delete_vim": task} + continue + elif command == "show": + print("not implemented show with vim_account") + sys.stdout.flush() + continue + elif command == "edit": + task = asyncio.ensure_future(self.edit_vim(vim_id, order_id)) + if vim_id not in self.lcm_vim_tasks: + self.lcm_vim_tasks[vim_id] = {} + self.lcm_vim_tasks[vim_id][order_id] = {"edit_vim": task} + continue + elif topic == "sdn": + _sdn_id = params["_id"] + if command == "create": + task = asyncio.ensure_future(self.create_sdn(params, order_id)) + if _sdn_id not in self.lcm_sdn_tasks: + self.lcm_sdn_tasks[_sdn_id] = {} + self.lcm_sdn_tasks[_sdn_id][order_id] = {"create_sdn": task} + continue + elif command == "delete": + self.cancel_tasks(topic, _sdn_id) + task = asyncio.ensure_future(self.delete_sdn(_sdn_id, order_id)) + if _sdn_id not in self.lcm_sdn_tasks: + self.lcm_sdn_tasks[_sdn_id] = {} + self.lcm_sdn_tasks[_sdn_id][order_id] = {"delete_sdn": task} + continue + elif command == "edit": + task = asyncio.ensure_future(self.edit_sdn(_sdn_id, order_id)) + if _sdn_id not in self.lcm_sdn_tasks: + self.lcm_sdn_tasks[_sdn_id] = {} + self.lcm_sdn_tasks[_sdn_id][order_id] = {"edit_sdn": task} + continue + self.logger.critical("unknown topic {} and command '{}'".format(topic, command)) + except Exception as e: + if consecutive_errors == 5: + self.logger.error("Task Kafka task exit error too many errors. Exception: {}".format(e)) + break + else: + consecutive_errors += 1 + self.logger.error("Task Kafka Exception {}".format(e)) + await asyncio.sleep(1, loop=self.loop) + self.logger.debug("Task Kafka terminating") + # TODO + # self.cancel_tasks("ALL", "create") + # timeout = 200 + # while self.is_pending_tasks(): + # self.logger.debug("Task Kafka terminating. Waiting for tasks termination") + # await asyncio.sleep(2, loop=self.loop) + # timeout -= 2 + # if not timeout: + # self.cancel_tasks("ALL", "ALL") + self.logger.debug("Task Kafka exit") def start(self): self.loop = asyncio.get_event_loop() self.loop.run_until_complete(self.read_kafka()) self.loop.close() self.loop = None + if self.db: + self.db.db_disconnect() + if self.msg: + self.msg.disconnect() + if self.fs: + self.fs.fs_disconnect() def read_config_file(self, config_file): @@ -418,6 +1096,7 @@ class Lcm: return conf except Exception as e: self.logger.critical("At config file '{}': {}".format(config_file, e)) + exit(1) if __name__ == '__main__': @@ -425,41 +1104,4 @@ if __name__ == '__main__': config_file = "lcm.cfg" lcm = Lcm(config_file) - # # FOR TEST - # RO_VIM = "OST2_MRT" - # - # #FILL DATABASE - # with open("/home/atierno/OSM/osm/devops/descriptor-packages/vnfd/ping_vnf/src/ping_vnfd.yaml") as f: - # vnfd = yaml.load(f) - # vnfd_clean, _ = ROclient.remove_envelop("vnfd", vnfd) - # vnfd_clean["_admin"] = {"storage": "/home/atierno/OSM/osm/devops/descriptor-packages/vnfd/ping_vnf"} - # lcm.db.create("vnfd", vnfd_clean) - # with open("/home/atierno/OSM/osm/devops/descriptor-packages/vnfd/pong_vnf/src/pong_vnfd.yaml") as f: - # vnfd = yaml.load(f) - # vnfd_clean, _ = ROclient.remove_envelop("vnfd", vnfd) - # vnfd_clean["_admin"] = {"storage": "/home/atierno/OSM/osm/devops/descriptor-packages/vnfd/pong_vnf"} - # lcm.db.create("vnfd", vnfd_clean) - # with open("/home/atierno/OSM/osm/devops/descriptor-packages/nsd/ping_pong_ns/src/ping_pong_nsd.yaml") as f: - # nsd = yaml.load(f) - # nsd_clean, _ = ROclient.remove_envelop("nsd", nsd) - # nsd_clean["_admin"] = {"storage": "/home/atierno/OSM/osm/devops/descriptor-packages/nsd/ping_pong_ns"} - # lcm.db.create("nsd", nsd_clean) - # - # ns_request = { - # "id": "ns1", - # "nsr_id": "ns1", - # "name": "pingpongOne", - # "vim": RO_VIM, - # "nsd_id": nsd_clean["id"], # nsd_ping_pong - # } - # lcm.db.create("ns_request", ns_request) - # ns_request = { - # "id": "ns2", - # "nsr_id": "ns2", - # "name": "pingpongTwo", - # "vim": RO_VIM, - # "nsd_id": nsd_clean["id"], # nsd_ping_pong - # } - # lcm.db.create("ns_request", ns_request) - lcm.start()