X-Git-Url: https://osm.etsi.org/gitweb/?p=osm%2FNBI.git;a=blobdiff_plain;f=osm_nbi%2Fsubscriptions.py;h=6810ccd6a0a37a159e37cbda9d5618f0c8480643;hp=7df78d247f570972e8e218a9f5d8b0ad3adf4f68;hb=4568a372eb5a204e04d917213de03ec51f9110c1;hpb=65ca36d13f895d0a361d59a5962029d6e3ef7a99;ds=sidebyside diff --git a/osm_nbi/subscriptions.py b/osm_nbi/subscriptions.py index 7df78d2..6810ccd 100644 --- a/osm_nbi/subscriptions.py +++ b/osm_nbi/subscriptions.py @@ -24,23 +24,23 @@ import logging import threading import asyncio from http import HTTPStatus + from osm_common import dbmongo, dbmemory, msglocal, msgkafka from osm_common.dbbase import DbException from osm_common.msgbase import MsgException -from engine import EngineException +from osm_nbi.engine import EngineException +from osm_nbi.notifications import NsLcmNotification __author__ = "Alfonso Tierno " class SubscriptionException(Exception): - def __init__(self, message, http_code=HTTPStatus.BAD_REQUEST): self.http_code = http_code Exception.__init__(self, message) class SubscriptionThread(threading.Thread): - def __init__(self, config, engine): """ Constructor of class @@ -55,13 +55,92 @@ class SubscriptionThread(threading.Thread): self.engine = engine self.loop = None self.logger = logging.getLogger("nbi.subscriptions") - self.aiomain_task = None # asyncio task for receiving kafka bus + self.aiomain_task_admin = ( + None # asyncio task for receiving admin actions from kafka bus + ) + self.aiomain_task = ( + None # asyncio task for receiving normal actions from kafka bus + ) self.internal_session = { # used for a session to the engine methods - "_id": "subscription", - "id": "subscription", - "project_id": "admin", - "admin": True + "project_id": (), + "set_project": (), + "admin": True, + "force": False, + "public": None, + "method": "delete", } + self.nslcm = None + + async def start_kafka(self): + # timeout_wait_for_kafka = 3*60 + kafka_working = True + while not self.to_terminate: + try: + # bug 710 635. The library aiokafka does not recieve anything when the topci at kafka has not been + # created. + # Before subscribe, send dummy messages + await self.msg.aiowrite( + "admin", "echo", "dummy message", loop=self.loop + ) + await self.msg.aiowrite("ns", "echo", "dummy message", loop=self.loop) + await self.msg.aiowrite("nsi", "echo", "dummy message", loop=self.loop) + if not kafka_working: + self.logger.critical("kafka is working again") + kafka_working = True + if not self.aiomain_task_admin: + await asyncio.sleep(10, loop=self.loop) + self.logger.debug("Starting admin subscription task") + self.aiomain_task_admin = asyncio.ensure_future( + self.msg.aioread( + ("admin",), + loop=self.loop, + group_id=False, + aiocallback=self._msg_callback, + ), + loop=self.loop, + ) + if not self.aiomain_task: + await asyncio.sleep(10, loop=self.loop) + self.logger.debug("Starting non-admin subscription task") + self.aiomain_task = asyncio.ensure_future( + self.msg.aioread( + ("ns", "nsi"), + loop=self.loop, + aiocallback=self._msg_callback, + ), + loop=self.loop, + ) + done, _ = await asyncio.wait( + [self.aiomain_task, self.aiomain_task_admin], + timeout=None, + loop=self.loop, + return_when=asyncio.FIRST_COMPLETED, + ) + try: + if self.aiomain_task_admin in done: + exc = self.aiomain_task_admin.exception() + self.logger.error( + "admin subscription task exception: {}".format(exc) + ) + self.aiomain_task_admin = None + if self.aiomain_task in done: + exc = self.aiomain_task.exception() + self.logger.error( + "non-admin subscription task exception: {}".format(exc) + ) + self.aiomain_task = None + except asyncio.CancelledError: + pass + except Exception as e: + if self.to_terminate: + return + if kafka_working: + # logging only first time + self.logger.critical( + "Error accessing kafka '{}'. Retrying ...".format(e) + ) + kafka_working = False + await asyncio.sleep(10, loop=self.loop) def run(self): """ @@ -78,8 +157,11 @@ class SubscriptionThread(threading.Thread): self.db = dbmemory.DbMemory() self.db.db_connect(self.config["database"]) else: - raise SubscriptionException("Invalid configuration param '{}' at '[database]':'driver'".format( - self.config["database"]["driver"])) + raise SubscriptionException( + "Invalid configuration param '{}' at '[database]':'driver'".format( + self.config["database"]["driver"] + ) + ) if not self.msg: config_msg = self.config["message"].copy() config_msg["loop"] = self.loop @@ -90,30 +172,35 @@ class SubscriptionThread(threading.Thread): self.msg = msgkafka.MsgKafka() self.msg.connect(config_msg) else: - raise SubscriptionException("Invalid configuration param '{}' at '[message]':'driver'".format( - config_msg["driver"])) - + raise SubscriptionException( + "Invalid configuration param '{}' at '[message]':'driver'".format( + config_msg["driver"] + ) + ) + self.nslcm = NsLcmNotification(self.db) except (DbException, MsgException) as e: raise SubscriptionException(str(e), http_code=e.http_code) self.logger.debug("Starting") while not self.to_terminate: try: - self.aiomain_task = asyncio.ensure_future(self.msg.aioread(("ns", "nsi"), loop=self.loop, - callback=self._msg_callback), - loop=self.loop) - self.loop.run_until_complete(self.aiomain_task) + + self.loop.run_until_complete( + asyncio.ensure_future(self.start_kafka(), loop=self.loop) + ) # except asyncio.CancelledError: # break # if cancelled it should end, breaking loop except Exception as e: if not self.to_terminate: - self.logger.exception("Exception '{}' at messaging read loop".format(e), exc_info=True) + self.logger.exception( + "Exception '{}' at messaging read loop".format(e), exc_info=True + ) self.logger.debug("Finishing") self._stop() self.loop.close() - def _msg_callback(self, topic, command, params): + async def _msg_callback(self, topic, command, params): """ Callback to process a received message from kafka :param topic: topic received @@ -121,26 +208,128 @@ class SubscriptionThread(threading.Thread): :param params: rest of parameters :return: None """ + msg_to_send = [] try: if topic == "ns": - if command == "terminated" and params["operationState"] in ("COMPLETED", "PARTIALLY_COMPLETED"): + if command == "terminated" and params["operationState"] in ( + "COMPLETED", + "PARTIALLY_COMPLETED", + ): self.logger.debug("received ns terminated {}".format(params)) if params.get("autoremove"): - self.engine.del_item(self.internal_session, "nsrs", _id=params["nsr_id"]) - self.logger.debug("ns={} deleted from database".format(params["nsr_id"])) - return - if topic == "nsi": - if command == "terminated" and params["operationState"] in ("COMPLETED", "PARTIALLY_COMPLETED"): + self.engine.del_item( + self.internal_session, + "nsrs", + _id=params["nsr_id"], + not_send_msg=msg_to_send, + ) + self.logger.debug( + "ns={} deleted from database".format(params["nsr_id"]) + ) + # Check for nslcm notification + if isinstance(params, dict): + # Check availability of operationState and command + if ( + (not params.get("operationState")) + or (not command) + or (not params.get("operationParams")) + ): + self.logger.debug( + "Message can not be used for notification of nslcm" + ) + else: + nsd_id = params["operationParams"].get("nsdId") + ns_instance_id = params["operationParams"].get("nsInstanceId") + # Any one among nsd_id, ns_instance_id should be present. + if not (nsd_id or ns_instance_id): + self.logger.debug( + "Message can not be used for notification of nslcm" + ) + else: + op_state = params["operationState"] + event_details = { + "topic": topic, + "command": command.upper(), + "params": params, + } + subscribers = self.nslcm.get_subscribers( + nsd_id, + ns_instance_id, + command.upper(), + op_state, + event_details, + ) + # self.logger.debug("subscribers list: ") + # self.logger.debug(subscribers) + if subscribers: + asyncio.ensure_future( + self.nslcm.send_notifications( + subscribers, loop=self.loop + ), + loop=self.loop, + ) + else: + self.logger.debug( + "Message can not be used for notification of nslcm" + ) + elif topic == "nsi": + if command == "terminated" and params["operationState"] in ( + "COMPLETED", + "PARTIALLY_COMPLETED", + ): self.logger.debug("received nsi terminated {}".format(params)) if params.get("autoremove"): - self.engine.del_item(self.internal_session, "nsis", _id=params["nsir_id"]) - self.logger.debug("nsis={} deleted from database".format(params["nsir_id"])) - return + self.engine.del_item( + self.internal_session, + "nsis", + _id=params["nsir_id"], + not_send_msg=msg_to_send, + ) + self.logger.debug( + "nsis={} deleted from database".format(params["nsir_id"]) + ) + elif topic == "admin": + self.logger.debug("received {} {} {}".format(topic, command, params)) + if command in ["echo", "ping"]: # ignored commands + pass + elif command == "revoke_token": + if params: + if isinstance(params, dict) and "_id" in params: + tid = params.get("_id") + self.engine.authenticator.tokens_cache.pop(tid, None) + self.logger.debug( + "token '{}' removed from token_cache".format(tid) + ) + else: + self.logger.debug( + "unrecognized params in command '{} {}': {}".format( + topic, command, params + ) + ) + else: + self.engine.authenticator.tokens_cache.clear() + self.logger.debug("token_cache cleared") + else: + self.logger.debug( + "unrecognized command '{} {}'".format(topic, command) + ) + # writing to kafka must be done with our own loop. For this reason it is not allowed Engine to do that, + # but content to be written is stored at msg_to_send + for msg in msg_to_send: + await self.msg.aiowrite(*msg, loop=self.loop) except (EngineException, DbException, MsgException) as e: - self.logger.error("Error while processing topic={} command={}: {}".format(topic, command, e)) + self.logger.error( + "Error while processing topic={} command={}: {}".format( + topic, command, e + ) + ) except Exception as e: - self.logger.exception("Exception while processing topic={} command={}: {}".format(topic, command, e), - exc_info=True) + self.logger.exception( + "Exception while processing topic={} command={}: {}".format( + topic, command, e + ), + exc_info=True, + ) def _stop(self): """ @@ -162,4 +351,7 @@ class SubscriptionThread(threading.Thread): :return: None """ self.to_terminate = True - self.loop.call_soon_threadsafe(self.aiomain_task.cancel) + if self.aiomain_task: + self.loop.call_soon_threadsafe(self.aiomain_task.cancel) + if self.aiomain_task_admin: + self.loop.call_soon_threadsafe(self.aiomain_task_admin.cancel)