blob: 1f9f3c9aebb067549b4ec2258f7ee35098241928 [file] [log] [blame]
tiernoc0e42e22018-05-11 11:36:10 +02001#!/usr/bin/python3
2# -*- coding: utf-8 -*-
3
tierno2e215512018-11-28 09:37:52 +00004##
5# Copyright 2018 Telefonica S.A.
6#
7# Licensed under the Apache License, Version 2.0 (the "License"); you may
8# not use this file except in compliance with the License. You may obtain
9# a copy of the License at
10#
11# http://www.apache.org/licenses/LICENSE-2.0
12#
13# Unless required by applicable law or agreed to in writing, software
14# distributed under the License is distributed on an "AS IS" BASIS, WITHOUT
15# WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. See the
16# License for the specific language governing permissions and limitations
17# under the License.
18##
19
quilesj7e13aeb2019-10-08 13:34:55 +020020
21# DEBUG WITH PDB
22import os
23import pdb
24
tiernoc0e42e22018-05-11 11:36:10 +020025import asyncio
26import yaml
tierno275411e2018-05-16 14:33:32 +020027import logging
28import logging.handlers
29import getopt
tierno275411e2018-05-16 14:33:32 +020030import sys
tierno59d22d22018-09-25 18:10:19 +020031
tiernob996d942020-07-03 14:52:28 +000032from osm_lcm import ns, prometheus, vim_sdn, netslice
tierno69f0d382020-05-07 13:08:09 +000033from osm_lcm.ng_ro import NgRoException, NgRoClient
34from osm_lcm.ROclient import ROClient, ROClientException
quilesj7e13aeb2019-10-08 13:34:55 +020035
tierno94f06112020-02-11 12:38:19 +000036from time import time
tierno8069ce52019-08-28 15:34:33 +000037from osm_lcm.lcm_utils import versiontuple, LcmException, TaskRegistry, LcmExceptionExit
tiernoa4dea5a2020-01-05 16:29:30 +000038from osm_lcm import version as lcm_version, version_date as lcm_version_date
tierno8069ce52019-08-28 15:34:33 +000039
bravof922c4172020-11-24 21:21:43 -030040from osm_common import msglocal, msgkafka
tierno98768132018-09-11 12:07:21 +020041from osm_common import version as common_version
tierno59d22d22018-09-25 18:10:19 +020042from osm_common.dbbase import DbException
tiernoc0e42e22018-05-11 11:36:10 +020043from osm_common.fsbase import FsException
44from osm_common.msgbase import MsgException
bravof922c4172020-11-24 21:21:43 -030045from osm_lcm.data_utils.database.database import Database
46from osm_lcm.data_utils.filesystem.filesystem import Filesystem
tierno275411e2018-05-16 14:33:32 +020047from os import environ, path
tierno16427352019-04-22 11:37:36 +000048from random import choice as random_choice
tierno59d22d22018-09-25 18:10:19 +020049from n2vc import version as n2vc_version
bravof922c4172020-11-24 21:21:43 -030050import traceback
tiernoc0e42e22018-05-11 11:36:10 +020051
quilesj7e13aeb2019-10-08 13:34:55 +020052if os.getenv('OSMLCM_PDB_DEBUG', None) is not None:
53 pdb.set_trace()
54
tiernoc0e42e22018-05-11 11:36:10 +020055
tierno275411e2018-05-16 14:33:32 +020056__author__ = "Alfonso Tierno"
tiernoe64f7fb2019-09-11 08:55:52 +000057min_RO_version = "6.0.2"
tierno6e9d2eb2018-09-12 17:47:18 +020058min_n2vc_version = "0.0.2"
quilesj7e13aeb2019-10-08 13:34:55 +020059
tierno16427352019-04-22 11:37:36 +000060min_common_version = "0.1.19"
tierno3e359b12019-02-03 02:29:13 +010061health_check_file = path.expanduser("~") + "/time_last_ping" # TODO find better location for this file
tierno275411e2018-05-16 14:33:32 +020062
63
tiernoc0e42e22018-05-11 11:36:10 +020064class Lcm:
65
tiernoa9843d82018-10-24 10:44:20 +020066 ping_interval_pace = 120 # how many time ping is send once is confirmed all is running
tiernof578e552018-11-08 19:07:20 +010067 ping_interval_boot = 5 # how many time ping is sent when booting
tierno991e95d2020-07-21 12:41:25 +000068 cfg_logger_name = {"message": "lcm.msg", "database": "lcm.db", "storage": "lcm.fs", "tsdb": "lcm.prometheus"}
69 # ^ contains for each section at lcm.cfg the used logger name
tiernoa9843d82018-10-24 10:44:20 +020070
tierno59d22d22018-09-25 18:10:19 +020071 def __init__(self, config_file, loop=None):
tiernoc0e42e22018-05-11 11:36:10 +020072 """
73 Init, Connect to database, filesystem storage, and messaging
74 :param config: two level dictionary with configuration. Top level should contain 'database', 'storage',
75 :return: None
76 """
tiernoc0e42e22018-05-11 11:36:10 +020077 self.db = None
78 self.msg = None
tierno16427352019-04-22 11:37:36 +000079 self.msg_admin = None
tiernoc0e42e22018-05-11 11:36:10 +020080 self.fs = None
81 self.pings_not_received = 1
tiernoc2564fe2019-01-28 16:18:56 +000082 self.consecutive_errors = 0
83 self.first_start = False
tiernoc0e42e22018-05-11 11:36:10 +020084
tiernoc0e42e22018-05-11 11:36:10 +020085 # logging
86 self.logger = logging.getLogger('lcm')
tierno16427352019-04-22 11:37:36 +000087 # get id
88 self.worker_id = self.get_process_id()
tiernoc0e42e22018-05-11 11:36:10 +020089 # load configuration
90 config = self.read_config_file(config_file)
91 self.config = config
tierno744303e2020-01-13 16:46:31 +000092 self.config["ro_config"] = {
tierno69f0d382020-05-07 13:08:09 +000093 "ng": config["RO"].get("ng", False),
94 "uri": config["RO"].get("uri"),
tierno750b2452018-05-17 16:39:29 +020095 "tenant": config.get("tenant", "osm"),
tierno69f0d382020-05-07 13:08:09 +000096 "logger_name": "lcm.roclient",
97 "loglevel": config["RO"].get("loglevel", "ERROR"),
tiernoc0e42e22018-05-11 11:36:10 +020098 }
tierno69f0d382020-05-07 13:08:09 +000099 if not self.config["ro_config"]["uri"]:
tierno2357f4e2020-10-19 16:38:59 +0000100 self.config["ro_config"]["uri"] = "http://{}:{}/".format(config["RO"]["host"], config["RO"]["port"])
101 elif "/ro" in self.config["ro_config"]["uri"][-4:] or "/openmano" in self.config["ro_config"]["uri"][-10:]:
102 # uri ends with '/ro', '/ro/', '/openmano', '/openmano/'
103 index = self.config["ro_config"]["uri"][-1].rfind("/")
104 self.config["ro_config"]["uri"] = self.config["ro_config"]["uri"][index+1]
tiernoc0e42e22018-05-11 11:36:10 +0200105
tierno59d22d22018-09-25 18:10:19 +0200106 self.loop = loop or asyncio.get_event_loop()
tierno2357f4e2020-10-19 16:38:59 +0000107 self.ns = self.netslice = self.vim = self.wim = self.sdn = self.k8scluster = self.k8srepo = None
tiernoc0e42e22018-05-11 11:36:10 +0200108
109 # logging
110 log_format_simple = "%(asctime)s %(levelname)s %(name)s %(filename)s:%(lineno)s %(message)s"
111 log_formatter_simple = logging.Formatter(log_format_simple, datefmt='%Y-%m-%dT%H:%M:%S')
112 config["database"]["logger_name"] = "lcm.db"
113 config["storage"]["logger_name"] = "lcm.fs"
114 config["message"]["logger_name"] = "lcm.msg"
tierno86aa62f2018-08-20 11:57:04 +0000115 if config["global"].get("logfile"):
tiernoc0e42e22018-05-11 11:36:10 +0200116 file_handler = logging.handlers.RotatingFileHandler(config["global"]["logfile"],
117 maxBytes=100e6, backupCount=9, delay=0)
118 file_handler.setFormatter(log_formatter_simple)
119 self.logger.addHandler(file_handler)
tierno86aa62f2018-08-20 11:57:04 +0000120 if not config["global"].get("nologging"):
tiernoc0e42e22018-05-11 11:36:10 +0200121 str_handler = logging.StreamHandler()
122 str_handler.setFormatter(log_formatter_simple)
123 self.logger.addHandler(str_handler)
124
125 if config["global"].get("loglevel"):
126 self.logger.setLevel(config["global"]["loglevel"])
127
128 # logging other modules
tierno991e95d2020-07-21 12:41:25 +0000129 for k1, logname in self.cfg_logger_name.items():
tiernoc0e42e22018-05-11 11:36:10 +0200130 config[k1]["logger_name"] = logname
131 logger_module = logging.getLogger(logname)
tierno86aa62f2018-08-20 11:57:04 +0000132 if config[k1].get("logfile"):
tiernoc0e42e22018-05-11 11:36:10 +0200133 file_handler = logging.handlers.RotatingFileHandler(config[k1]["logfile"],
134 maxBytes=100e6, backupCount=9, delay=0)
135 file_handler.setFormatter(log_formatter_simple)
136 logger_module.addHandler(file_handler)
tierno86aa62f2018-08-20 11:57:04 +0000137 if config[k1].get("loglevel"):
tiernoc0e42e22018-05-11 11:36:10 +0200138 logger_module.setLevel(config[k1]["loglevel"])
tierno86aa62f2018-08-20 11:57:04 +0000139 self.logger.critical("starting osm/lcm version {} {}".format(lcm_version, lcm_version_date))
tierno59d22d22018-09-25 18:10:19 +0200140
tiernoc0e42e22018-05-11 11:36:10 +0200141 # check version of N2VC
142 # TODO enhance with int conversion or from distutils.version import LooseVersion
143 # or with list(map(int, version.split(".")))
tierno59d22d22018-09-25 18:10:19 +0200144 if versiontuple(n2vc_version) < versiontuple(min_n2vc_version):
tierno6e9d2eb2018-09-12 17:47:18 +0200145 raise LcmException("Not compatible osm/N2VC version '{}'. Needed '{}' or higher".format(
tierno59d22d22018-09-25 18:10:19 +0200146 n2vc_version, min_n2vc_version))
147 # check version of common
tierno27246d82018-09-27 15:59:09 +0200148 if versiontuple(common_version) < versiontuple(min_common_version):
tierno6e9d2eb2018-09-12 17:47:18 +0200149 raise LcmException("Not compatible osm/common version '{}'. Needed '{}' or higher".format(
150 common_version, min_common_version))
tierno22f4f9c2018-06-11 18:53:39 +0200151
tiernoc0e42e22018-05-11 11:36:10 +0200152 try:
bravof922c4172020-11-24 21:21:43 -0300153 self.db = Database(config).instance.db
tiernoc0e42e22018-05-11 11:36:10 +0200154
bravof922c4172020-11-24 21:21:43 -0300155 self.fs = Filesystem(config).instance.fs
sousaedu7c45b4a2021-07-26 15:24:21 +0200156 self.fs.sync()
tiernoc0e42e22018-05-11 11:36:10 +0200157
quilesj7e13aeb2019-10-08 13:34:55 +0200158 # copy message configuration in order to remove 'group_id' for msg_admin
tiernoc2564fe2019-01-28 16:18:56 +0000159 config_message = config["message"].copy()
160 config_message["loop"] = self.loop
161 if config_message["driver"] == "local":
tiernoc0e42e22018-05-11 11:36:10 +0200162 self.msg = msglocal.MsgLocal()
tiernoc2564fe2019-01-28 16:18:56 +0000163 self.msg.connect(config_message)
tierno16427352019-04-22 11:37:36 +0000164 self.msg_admin = msglocal.MsgLocal()
165 config_message.pop("group_id", None)
166 self.msg_admin.connect(config_message)
tiernoc2564fe2019-01-28 16:18:56 +0000167 elif config_message["driver"] == "kafka":
tiernoc0e42e22018-05-11 11:36:10 +0200168 self.msg = msgkafka.MsgKafka()
tiernoc2564fe2019-01-28 16:18:56 +0000169 self.msg.connect(config_message)
tierno16427352019-04-22 11:37:36 +0000170 self.msg_admin = msgkafka.MsgKafka()
171 config_message.pop("group_id", None)
172 self.msg_admin.connect(config_message)
tiernoc0e42e22018-05-11 11:36:10 +0200173 else:
174 raise LcmException("Invalid configuration param '{}' at '[message]':'driver'".format(
tiernoc2564fe2019-01-28 16:18:56 +0000175 config["message"]["driver"]))
tiernoc0e42e22018-05-11 11:36:10 +0200176 except (DbException, FsException, MsgException) as e:
177 self.logger.critical(str(e), exc_info=True)
178 raise LcmException(str(e))
179
kuused124bfe2019-06-18 12:09:24 +0200180 # contains created tasks/futures to be able to cancel
bravof922c4172020-11-24 21:21:43 -0300181 self.lcm_tasks = TaskRegistry(self.worker_id, self.logger)
kuused124bfe2019-06-18 12:09:24 +0200182
tierno991e95d2020-07-21 12:41:25 +0000183 if self.config.get("tsdb") and self.config["tsdb"].get("driver"):
184 if self.config["tsdb"]["driver"] == "prometheus":
bravof922c4172020-11-24 21:21:43 -0300185 self.prometheus = prometheus.Prometheus(self.config["tsdb"], self.worker_id, self.loop)
tierno991e95d2020-07-21 12:41:25 +0000186 else:
187 raise LcmException("Invalid configuration param '{}' at '[tsdb]':'driver'".format(
188 config["tsdb"]["driver"]))
tiernob996d942020-07-03 14:52:28 +0000189 else:
190 self.prometheus = None
tierno59d22d22018-09-25 18:10:19 +0200191
tierno22f4f9c2018-06-11 18:53:39 +0200192 async def check_RO_version(self):
tiernoe64f7fb2019-09-11 08:55:52 +0000193 tries = 14
194 last_error = None
195 while True:
tierno2357f4e2020-10-19 16:38:59 +0000196 ro_uri = self.config["ro_config"]["uri"]
tiernoe64f7fb2019-09-11 08:55:52 +0000197 try:
tierno2357f4e2020-10-19 16:38:59 +0000198 # try new RO, if fail old RO
199 try:
200 self.config["ro_config"]["uri"] = ro_uri + "ro"
tierno69f0d382020-05-07 13:08:09 +0000201 ro_server = NgRoClient(self.loop, **self.config["ro_config"])
tierno2357f4e2020-10-19 16:38:59 +0000202 ro_version = await ro_server.get_version()
203 self.config["ro_config"]["ng"] = True
204 except Exception:
205 self.config["ro_config"]["uri"] = ro_uri + "openmano"
tierno69f0d382020-05-07 13:08:09 +0000206 ro_server = ROClient(self.loop, **self.config["ro_config"])
tierno2357f4e2020-10-19 16:38:59 +0000207 ro_version = await ro_server.get_version()
208 self.config["ro_config"]["ng"] = False
tiernoe64f7fb2019-09-11 08:55:52 +0000209 if versiontuple(ro_version) < versiontuple(min_RO_version):
210 raise LcmException("Not compatible osm/RO version '{}'. Needed '{}' or higher".format(
211 ro_version, min_RO_version))
tierno2357f4e2020-10-19 16:38:59 +0000212 self.logger.info("Connected to RO version {} new-generation version {}".
213 format(ro_version, self.config["ro_config"]["ng"]))
tiernoe64f7fb2019-09-11 08:55:52 +0000214 return
tierno69f0d382020-05-07 13:08:09 +0000215 except (ROClientException, NgRoException) as e:
tierno2357f4e2020-10-19 16:38:59 +0000216 self.config["ro_config"]["uri"] = ro_uri
tiernoe64f7fb2019-09-11 08:55:52 +0000217 tries -= 1
bravof922c4172020-11-24 21:21:43 -0300218 traceback.print_tb(e.__traceback__)
tierno69f0d382020-05-07 13:08:09 +0000219 error_text = "Error while connecting to RO on {}: {}".format(self.config["ro_config"]["uri"], e)
tiernoe64f7fb2019-09-11 08:55:52 +0000220 if tries <= 0:
221 self.logger.critical(error_text)
222 raise LcmException(error_text)
223 if last_error != error_text:
224 last_error = error_text
225 self.logger.error(error_text + ". Waiting until {} seconds".format(5*tries))
226 await asyncio.sleep(5)
tierno22f4f9c2018-06-11 18:53:39 +0200227
tiernoc0e42e22018-05-11 11:36:10 +0200228 async def test(self, param=None):
229 self.logger.debug("Starting/Ending test task: {}".format(param))
230
tiernoc0e42e22018-05-11 11:36:10 +0200231 async def kafka_ping(self):
232 self.logger.debug("Task kafka_ping Enter")
233 consecutive_errors = 0
234 first_start = True
235 kafka_has_received = False
236 self.pings_not_received = 1
237 while True:
238 try:
tierno16427352019-04-22 11:37:36 +0000239 await self.msg_admin.aiowrite(
240 "admin", "ping",
241 {"from": "lcm", "to": "lcm", "worker_id": self.worker_id, "version": lcm_version},
242 self.loop)
tiernoc0e42e22018-05-11 11:36:10 +0200243 # time between pings are low when it is not received and at starting
tiernoa9843d82018-10-24 10:44:20 +0200244 wait_time = self.ping_interval_boot if not kafka_has_received else self.ping_interval_pace
tiernoc0e42e22018-05-11 11:36:10 +0200245 if not self.pings_not_received:
246 kafka_has_received = True
247 self.pings_not_received += 1
248 await asyncio.sleep(wait_time, loop=self.loop)
249 if self.pings_not_received > 10:
250 raise LcmException("It is not receiving pings from Kafka bus")
251 consecutive_errors = 0
252 first_start = False
253 except LcmException:
254 raise
255 except Exception as e:
256 # if not first_start is the first time after starting. So leave more time and wait
257 # to allow kafka starts
258 if consecutive_errors == 8 if not first_start else 30:
259 self.logger.error("Task kafka_read task exit error too many errors. Exception: {}".format(e))
260 raise
261 consecutive_errors += 1
262 self.logger.error("Task kafka_read retrying after Exception {}".format(e))
tierno16427352019-04-22 11:37:36 +0000263 wait_time = 2 if not first_start else 5
tiernoc0e42e22018-05-11 11:36:10 +0200264 await asyncio.sleep(wait_time, loop=self.loop)
265
gcalvinoed7f6d42018-12-14 14:44:56 +0100266 def kafka_read_callback(self, topic, command, params):
267 order_id = 1
268
269 if topic != "admin" and command != "ping":
270 self.logger.debug("Task kafka_read receives {} {}: {}".format(topic, command, params))
271 self.consecutive_errors = 0
272 self.first_start = False
273 order_id += 1
274 if command == "exit":
275 raise LcmExceptionExit
276 elif command.startswith("#"):
277 return
278 elif command == "echo":
279 # just for test
280 print(params)
281 sys.stdout.flush()
282 return
283 elif command == "test":
284 asyncio.Task(self.test(params), loop=self.loop)
285 return
286
287 if topic == "admin":
288 if command == "ping" and params["to"] == "lcm" and params["from"] == "lcm":
tierno16427352019-04-22 11:37:36 +0000289 if params.get("worker_id") != self.worker_id:
290 return
gcalvinoed7f6d42018-12-14 14:44:56 +0100291 self.pings_not_received = 0
tierno3e359b12019-02-03 02:29:13 +0100292 try:
293 with open(health_check_file, "w") as f:
294 f.write(str(time()))
295 except Exception as e:
296 self.logger.error("Cannot write into '{}' for healthcheck: {}".format(health_check_file, e))
gcalvinoed7f6d42018-12-14 14:44:56 +0100297 return
magnussonle9198bb2020-01-21 13:00:51 +0100298 elif topic == "pla":
299 if command == "placement":
300 self.ns.update_nsrs_with_pla_result(params)
301 return
calvinosanch9f9c6f22019-11-04 13:37:39 +0100302 elif topic == "k8scluster":
303 if command == "create" or command == "created":
304 k8scluster_id = params.get("_id")
305 task = asyncio.ensure_future(self.k8scluster.create(params, order_id))
306 self.lcm_tasks.register("k8scluster", k8scluster_id, order_id, "k8scluster_create", task)
307 return
308 elif command == "delete" or command == "deleted":
309 k8scluster_id = params.get("_id")
310 task = asyncio.ensure_future(self.k8scluster.delete(params, order_id))
311 self.lcm_tasks.register("k8scluster", k8scluster_id, order_id, "k8scluster_delete", task)
312 return
313 elif topic == "k8srepo":
314 if command == "create" or command == "created":
315 k8srepo_id = params.get("_id")
316 self.logger.debug("k8srepo_id = {}".format(k8srepo_id))
317 task = asyncio.ensure_future(self.k8srepo.create(params, order_id))
318 self.lcm_tasks.register("k8srepo", k8srepo_id, order_id, "k8srepo_create", task)
319 return
320 elif command == "delete" or command == "deleted":
321 k8srepo_id = params.get("_id")
322 task = asyncio.ensure_future(self.k8srepo.delete(params, order_id))
323 self.lcm_tasks.register("k8srepo", k8srepo_id, order_id, "k8srepo_delete", task)
324 return
gcalvinoed7f6d42018-12-14 14:44:56 +0100325 elif topic == "ns":
tierno307425f2020-01-26 23:35:59 +0000326 if command == "instantiate":
gcalvinoed7f6d42018-12-14 14:44:56 +0100327 # self.logger.debug("Deploying NS {}".format(nsr_id))
328 nslcmop = params
329 nslcmop_id = nslcmop["_id"]
330 nsr_id = nslcmop["nsInstanceId"]
331 task = asyncio.ensure_future(self.ns.instantiate(nsr_id, nslcmop_id))
332 self.lcm_tasks.register("ns", nsr_id, nslcmop_id, "ns_instantiate", task)
333 return
tierno307425f2020-01-26 23:35:59 +0000334 elif command == "terminate":
gcalvinoed7f6d42018-12-14 14:44:56 +0100335 # self.logger.debug("Deleting NS {}".format(nsr_id))
336 nslcmop = params
337 nslcmop_id = nslcmop["_id"]
338 nsr_id = nslcmop["nsInstanceId"]
339 self.lcm_tasks.cancel(topic, nsr_id)
340 task = asyncio.ensure_future(self.ns.terminate(nsr_id, nslcmop_id))
341 self.lcm_tasks.register("ns", nsr_id, nslcmop_id, "ns_terminate", task)
342 return
343 elif command == "action":
344 # self.logger.debug("Update NS {}".format(nsr_id))
345 nslcmop = params
346 nslcmop_id = nslcmop["_id"]
347 nsr_id = nslcmop["nsInstanceId"]
348 task = asyncio.ensure_future(self.ns.action(nsr_id, nslcmop_id))
349 self.lcm_tasks.register("ns", nsr_id, nslcmop_id, "ns_action", task)
350 return
351 elif command == "scale":
352 # self.logger.debug("Update NS {}".format(nsr_id))
353 nslcmop = params
354 nslcmop_id = nslcmop["_id"]
355 nsr_id = nslcmop["nsInstanceId"]
356 task = asyncio.ensure_future(self.ns.scale(nsr_id, nslcmop_id))
357 self.lcm_tasks.register("ns", nsr_id, nslcmop_id, "ns_scale", task)
358 return
359 elif command == "show":
tiernoc2564fe2019-01-28 16:18:56 +0000360 nsr_id = params
gcalvinoed7f6d42018-12-14 14:44:56 +0100361 try:
362 db_nsr = self.db.get_one("nsrs", {"_id": nsr_id})
363 print("nsr:\n _id={}\n operational-status: {}\n config-status: {}"
364 "\n detailed-status: {}\n deploy: {}\n tasks: {}"
365 "".format(nsr_id, db_nsr["operational-status"], db_nsr["config-status"],
366 db_nsr["detailed-status"],
367 db_nsr["_admin"]["deployed"], self.lcm_ns_tasks.get(nsr_id)))
368 except Exception as e:
369 print("nsr {} not found: {}".format(nsr_id, e))
370 sys.stdout.flush()
371 return
372 elif command == "deleted":
373 return # TODO cleaning of task just in case should be done
374 elif command in ("terminated", "instantiated", "scaled", "actioned"): # "scaled-cooldown-time"
375 return
376 elif topic == "nsi": # netslice LCM processes (instantiate, terminate, etc)
tierno307425f2020-01-26 23:35:59 +0000377 if command == "instantiate":
gcalvinoed7f6d42018-12-14 14:44:56 +0100378 # self.logger.debug("Instantiating Network Slice {}".format(nsilcmop["netsliceInstanceId"]))
379 nsilcmop = params
380 nsilcmop_id = nsilcmop["_id"] # slice operation id
381 nsir_id = nsilcmop["netsliceInstanceId"] # slice record id
382 task = asyncio.ensure_future(self.netslice.instantiate(nsir_id, nsilcmop_id))
383 self.lcm_tasks.register("nsi", nsir_id, nsilcmop_id, "nsi_instantiate", task)
384 return
tierno307425f2020-01-26 23:35:59 +0000385 elif command == "terminate":
gcalvinoed7f6d42018-12-14 14:44:56 +0100386 # self.logger.debug("Terminating Network Slice NS {}".format(nsilcmop["netsliceInstanceId"]))
387 nsilcmop = params
388 nsilcmop_id = nsilcmop["_id"] # slice operation id
389 nsir_id = nsilcmop["netsliceInstanceId"] # slice record id
390 self.lcm_tasks.cancel(topic, nsir_id)
391 task = asyncio.ensure_future(self.netslice.terminate(nsir_id, nsilcmop_id))
392 self.lcm_tasks.register("nsi", nsir_id, nsilcmop_id, "nsi_terminate", task)
393 return
394 elif command == "show":
tiernoc2564fe2019-01-28 16:18:56 +0000395 nsir_id = params
gcalvinoed7f6d42018-12-14 14:44:56 +0100396 try:
397 db_nsir = self.db.get_one("nsirs", {"_id": nsir_id})
398 print("nsir:\n _id={}\n operational-status: {}\n config-status: {}"
399 "\n detailed-status: {}\n deploy: {}\n tasks: {}"
400 "".format(nsir_id, db_nsir["operational-status"], db_nsir["config-status"],
401 db_nsir["detailed-status"],
402 db_nsir["_admin"]["deployed"], self.lcm_netslice_tasks.get(nsir_id)))
403 except Exception as e:
404 print("nsir {} not found: {}".format(nsir_id, e))
405 sys.stdout.flush()
406 return
407 elif command == "deleted":
408 return # TODO cleaning of task just in case should be done
409 elif command in ("terminated", "instantiated", "scaled", "actioned"): # "scaled-cooldown-time"
410 return
411 elif topic == "vim_account":
412 vim_id = params["_id"]
tiernof210c1c2019-10-16 09:09:58 +0000413 if command in ("create", "created"):
tierno2357f4e2020-10-19 16:38:59 +0000414 if not self.config["ro_config"].get("ng"):
415 task = asyncio.ensure_future(self.vim.create(params, order_id))
416 self.lcm_tasks.register("vim_account", vim_id, order_id, "vim_create", task)
gcalvinoed7f6d42018-12-14 14:44:56 +0100417 return
calvinosanch9f9c6f22019-11-04 13:37:39 +0100418 elif command == "delete" or command == "deleted":
gcalvinoed7f6d42018-12-14 14:44:56 +0100419 self.lcm_tasks.cancel(topic, vim_id)
kuuse6a470c62019-07-10 13:52:45 +0200420 task = asyncio.ensure_future(self.vim.delete(params, order_id))
gcalvinoed7f6d42018-12-14 14:44:56 +0100421 self.lcm_tasks.register("vim_account", vim_id, order_id, "vim_delete", task)
422 return
423 elif command == "show":
424 print("not implemented show with vim_account")
425 sys.stdout.flush()
426 return
tiernof210c1c2019-10-16 09:09:58 +0000427 elif command in ("edit", "edited"):
tierno2357f4e2020-10-19 16:38:59 +0000428 if not self.config["ro_config"].get("ng"):
429 task = asyncio.ensure_future(self.vim.edit(params, order_id))
430 self.lcm_tasks.register("vim_account", vim_id, order_id, "vim_edit", task)
gcalvinoed7f6d42018-12-14 14:44:56 +0100431 return
tiernof210c1c2019-10-16 09:09:58 +0000432 elif command == "deleted":
433 return # TODO cleaning of task just in case should be done
gcalvinoed7f6d42018-12-14 14:44:56 +0100434 elif topic == "wim_account":
435 wim_id = params["_id"]
tiernof210c1c2019-10-16 09:09:58 +0000436 if command in ("create", "created"):
tierno2357f4e2020-10-19 16:38:59 +0000437 if not self.config["ro_config"].get("ng"):
438 task = asyncio.ensure_future(self.wim.create(params, order_id))
439 self.lcm_tasks.register("wim_account", wim_id, order_id, "wim_create", task)
gcalvinoed7f6d42018-12-14 14:44:56 +0100440 return
calvinosanch9f9c6f22019-11-04 13:37:39 +0100441 elif command == "delete" or command == "deleted":
gcalvinoed7f6d42018-12-14 14:44:56 +0100442 self.lcm_tasks.cancel(topic, wim_id)
kuuse6a470c62019-07-10 13:52:45 +0200443 task = asyncio.ensure_future(self.wim.delete(params, order_id))
gcalvinoed7f6d42018-12-14 14:44:56 +0100444 self.lcm_tasks.register("wim_account", wim_id, order_id, "wim_delete", task)
445 return
446 elif command == "show":
447 print("not implemented show with wim_account")
448 sys.stdout.flush()
449 return
tiernof210c1c2019-10-16 09:09:58 +0000450 elif command in ("edit", "edited"):
gcalvinoed7f6d42018-12-14 14:44:56 +0100451 task = asyncio.ensure_future(self.wim.edit(params, order_id))
452 self.lcm_tasks.register("wim_account", wim_id, order_id, "wim_edit", task)
453 return
tiernof210c1c2019-10-16 09:09:58 +0000454 elif command == "deleted":
455 return # TODO cleaning of task just in case should be done
gcalvinoed7f6d42018-12-14 14:44:56 +0100456 elif topic == "sdn":
457 _sdn_id = params["_id"]
tiernof210c1c2019-10-16 09:09:58 +0000458 if command in ("create", "created"):
tierno2357f4e2020-10-19 16:38:59 +0000459 if not self.config["ro_config"].get("ng"):
460 task = asyncio.ensure_future(self.sdn.create(params, order_id))
461 self.lcm_tasks.register("sdn", _sdn_id, order_id, "sdn_create", task)
gcalvinoed7f6d42018-12-14 14:44:56 +0100462 return
calvinosanch9f9c6f22019-11-04 13:37:39 +0100463 elif command == "delete" or command == "deleted":
gcalvinoed7f6d42018-12-14 14:44:56 +0100464 self.lcm_tasks.cancel(topic, _sdn_id)
kuuse6a470c62019-07-10 13:52:45 +0200465 task = asyncio.ensure_future(self.sdn.delete(params, order_id))
gcalvinoed7f6d42018-12-14 14:44:56 +0100466 self.lcm_tasks.register("sdn", _sdn_id, order_id, "sdn_delete", task)
467 return
tiernof210c1c2019-10-16 09:09:58 +0000468 elif command in ("edit", "edited"):
gcalvinoed7f6d42018-12-14 14:44:56 +0100469 task = asyncio.ensure_future(self.sdn.edit(params, order_id))
470 self.lcm_tasks.register("sdn", _sdn_id, order_id, "sdn_edit", task)
471 return
tiernof210c1c2019-10-16 09:09:58 +0000472 elif command == "deleted":
473 return # TODO cleaning of task just in case should be done
gcalvinoed7f6d42018-12-14 14:44:56 +0100474 self.logger.critical("unknown topic {} and command '{}'".format(topic, command))
475
tiernoc0e42e22018-05-11 11:36:10 +0200476 async def kafka_read(self):
tierno16427352019-04-22 11:37:36 +0000477 self.logger.debug("Task kafka_read Enter with worker_id={}".format(self.worker_id))
tiernoc0e42e22018-05-11 11:36:10 +0200478 # future = asyncio.Future()
gcalvinoed7f6d42018-12-14 14:44:56 +0100479 self.consecutive_errors = 0
480 self.first_start = True
481 while self.consecutive_errors < 10:
tiernoc0e42e22018-05-11 11:36:10 +0200482 try:
magnussonle9198bb2020-01-21 13:00:51 +0100483 topics = ("ns", "vim_account", "wim_account", "sdn", "nsi", "k8scluster", "k8srepo", "pla")
tierno16427352019-04-22 11:37:36 +0000484 topics_admin = ("admin", )
485 await asyncio.gather(
tierno247439e2020-07-16 11:26:02 +0000486 self.msg.aioread(topics, self.loop, self.kafka_read_callback, from_beginning=True),
tierno16427352019-04-22 11:37:36 +0000487 self.msg_admin.aioread(topics_admin, self.loop, self.kafka_read_callback, group_id=False)
488 )
tiernoc0e42e22018-05-11 11:36:10 +0200489
gcalvinoed7f6d42018-12-14 14:44:56 +0100490 except LcmExceptionExit:
491 self.logger.debug("Bye!")
492 break
tiernoc0e42e22018-05-11 11:36:10 +0200493 except Exception as e:
494 # if not first_start is the first time after starting. So leave more time and wait
495 # to allow kafka starts
gcalvinoed7f6d42018-12-14 14:44:56 +0100496 if self.consecutive_errors == 8 if not self.first_start else 30:
tiernoc0e42e22018-05-11 11:36:10 +0200497 self.logger.error("Task kafka_read task exit error too many errors. Exception: {}".format(e))
498 raise
gcalvinoed7f6d42018-12-14 14:44:56 +0100499 self.consecutive_errors += 1
tiernoc0e42e22018-05-11 11:36:10 +0200500 self.logger.error("Task kafka_read retrying after Exception {}".format(e))
gcalvinoed7f6d42018-12-14 14:44:56 +0100501 wait_time = 2 if not self.first_start else 5
tiernoc0e42e22018-05-11 11:36:10 +0200502 await asyncio.sleep(wait_time, loop=self.loop)
503
504 # self.logger.debug("Task kafka_read terminating")
505 self.logger.debug("Task kafka_read exit")
506
507 def start(self):
tierno22f4f9c2018-06-11 18:53:39 +0200508
509 # check RO version
510 self.loop.run_until_complete(self.check_RO_version())
511
bravof922c4172020-11-24 21:21:43 -0300512 self.ns = ns.NsLcm(self.msg, self.lcm_tasks, self.config, self.loop, self.prometheus)
513 self.netslice = netslice.NetsliceLcm(self.msg, self.lcm_tasks, self.config, self.loop,
tierno2357f4e2020-10-19 16:38:59 +0000514 self.ns)
bravof922c4172020-11-24 21:21:43 -0300515 self.vim = vim_sdn.VimLcm(self.msg, self.lcm_tasks, self.config, self.loop)
516 self.wim = vim_sdn.WimLcm(self.msg, self.lcm_tasks, self.config, self.loop)
517 self.sdn = vim_sdn.SdnLcm(self.msg, self.lcm_tasks, self.config, self.loop)
518 self.k8scluster = vim_sdn.K8sClusterLcm(self.msg, self.lcm_tasks, self.config, self.loop)
519 self.k8srepo = vim_sdn.K8sRepoLcm(self.msg, self.lcm_tasks, self.config, self.loop)
tierno2357f4e2020-10-19 16:38:59 +0000520
tierno991e95d2020-07-21 12:41:25 +0000521 # configure tsdb prometheus
tiernob996d942020-07-03 14:52:28 +0000522 if self.prometheus:
523 self.loop.run_until_complete(self.prometheus.start())
524
tiernoc0e42e22018-05-11 11:36:10 +0200525 self.loop.run_until_complete(asyncio.gather(
526 self.kafka_read(),
527 self.kafka_ping()
528 ))
529 # TODO
530 # self.logger.debug("Terminating cancelling creation tasks")
tiernoca2e16a2018-06-29 15:25:24 +0200531 # self.lcm_tasks.cancel("ALL", "create")
tiernoc0e42e22018-05-11 11:36:10 +0200532 # timeout = 200
533 # while self.is_pending_tasks():
534 # self.logger.debug("Task kafka_read terminating. Waiting for tasks termination")
535 # await asyncio.sleep(2, loop=self.loop)
536 # timeout -= 2
537 # if not timeout:
tiernoca2e16a2018-06-29 15:25:24 +0200538 # self.lcm_tasks.cancel("ALL", "ALL")
tiernoc0e42e22018-05-11 11:36:10 +0200539 self.loop.close()
540 self.loop = None
541 if self.db:
542 self.db.db_disconnect()
543 if self.msg:
544 self.msg.disconnect()
tierno16427352019-04-22 11:37:36 +0000545 if self.msg_admin:
546 self.msg_admin.disconnect()
tiernoc0e42e22018-05-11 11:36:10 +0200547 if self.fs:
548 self.fs.fs_disconnect()
549
tiernoc0e42e22018-05-11 11:36:10 +0200550 def read_config_file(self, config_file):
551 # TODO make a [ini] + yaml inside parser
552 # the configparser library is not suitable, because it does not admit comments at the end of line,
553 # and not parse integer or boolean
554 try:
tierno744303e2020-01-13 16:46:31 +0000555 # read file as yaml format
tiernoc0e42e22018-05-11 11:36:10 +0200556 with open(config_file) as f:
tiernoda6fb102019-11-23 00:36:52 +0000557 conf = yaml.load(f, Loader=yaml.Loader)
tierno744303e2020-01-13 16:46:31 +0000558 # Ensure all sections are not empty
559 for k in ("global", "timeout", "RO", "VCA", "database", "storage", "message"):
560 if not conf.get(k):
561 conf[k] = {}
562
563 # read all environ that starts with OSMLCM_
tiernoc0e42e22018-05-11 11:36:10 +0200564 for k, v in environ.items():
565 if not k.startswith("OSMLCM_"):
566 continue
tierno744303e2020-01-13 16:46:31 +0000567 subject, _, item = k[7:].lower().partition("_")
568 if not item:
tierno17a612f2018-10-23 11:30:42 +0200569 continue
tierno744303e2020-01-13 16:46:31 +0000570 if subject in ("ro", "vca"):
tierno17a612f2018-10-23 11:30:42 +0200571 # put in capital letter
tierno744303e2020-01-13 16:46:31 +0000572 subject = subject.upper()
tiernoc0e42e22018-05-11 11:36:10 +0200573 try:
tierno744303e2020-01-13 16:46:31 +0000574 if item == "port" or subject == "timeout":
575 conf[subject][item] = int(v)
tiernoc0e42e22018-05-11 11:36:10 +0200576 else:
tierno744303e2020-01-13 16:46:31 +0000577 conf[subject][item] = v
tiernoc0e42e22018-05-11 11:36:10 +0200578 except Exception as e:
tierno744303e2020-01-13 16:46:31 +0000579 self.logger.warning("skipping environ '{}' on exception '{}'".format(k, e))
580
581 # backward compatibility of VCA parameters
582
583 if 'pubkey' in conf["VCA"]:
584 conf["VCA"]['public_key'] = conf["VCA"].pop('pubkey')
585 if 'cacert' in conf["VCA"]:
586 conf["VCA"]['ca_cert'] = conf["VCA"].pop('cacert')
587 if 'apiproxy' in conf["VCA"]:
588 conf["VCA"]['api_proxy'] = conf["VCA"].pop('apiproxy')
589
590 if 'enableosupgrade' in conf["VCA"]:
591 conf["VCA"]['enable_os_upgrade'] = conf["VCA"].pop('enableosupgrade')
592 if isinstance(conf["VCA"].get('enable_os_upgrade'), str):
593 if conf["VCA"]['enable_os_upgrade'].lower() == 'false':
594 conf["VCA"]['enable_os_upgrade'] = False
595 elif conf["VCA"]['enable_os_upgrade'].lower() == 'true':
596 conf["VCA"]['enable_os_upgrade'] = True
597
598 if 'aptmirror' in conf["VCA"]:
599 conf["VCA"]['apt_mirror'] = conf["VCA"].pop('aptmirror')
tiernoc0e42e22018-05-11 11:36:10 +0200600
601 return conf
602 except Exception as e:
603 self.logger.critical("At config file '{}': {}".format(config_file, e))
604 exit(1)
605
tierno16427352019-04-22 11:37:36 +0000606 @staticmethod
607 def get_process_id():
608 """
609 Obtain a unique ID for this process. If running from inside docker, it will get docker ID. If not it
610 will provide a random one
611 :return: Obtained ID
612 """
613 # Try getting docker id. If fails, get pid
614 try:
615 with open("/proc/self/cgroup", "r") as f:
616 text_id_ = f.readline()
617 _, _, text_id = text_id_.rpartition("/")
618 text_id = text_id.replace('\n', '')[:12]
619 if text_id:
620 return text_id
621 except Exception:
622 pass
623 # Return a random id
624 return ''.join(random_choice("0123456789abcdef") for _ in range(12))
625
tiernoc0e42e22018-05-11 11:36:10 +0200626
tierno275411e2018-05-16 14:33:32 +0200627def usage():
628 print("""Usage: {} [options]
quilesj7e13aeb2019-10-08 13:34:55 +0200629 -c|--config [configuration_file]: loads the configuration file (default: ./lcm.cfg)
tiernoa9843d82018-10-24 10:44:20 +0200630 --health-check: do not run lcm, but inspect kafka bus to determine if lcm is healthy
tierno275411e2018-05-16 14:33:32 +0200631 -h|--help: shows this help
632 """.format(sys.argv[0]))
tierno750b2452018-05-17 16:39:29 +0200633 # --log-socket-host HOST: send logs to this host")
634 # --log-socket-port PORT: send logs using this port (default: 9022)")
tierno275411e2018-05-16 14:33:32 +0200635
636
tiernoc0e42e22018-05-11 11:36:10 +0200637if __name__ == '__main__':
quilesj7e13aeb2019-10-08 13:34:55 +0200638
tierno275411e2018-05-16 14:33:32 +0200639 try:
tierno8c16b052020-02-05 15:08:32 +0000640 # print("SYS.PATH='{}'".format(sys.path))
tierno275411e2018-05-16 14:33:32 +0200641 # load parameters and configuration
quilesj7e13aeb2019-10-08 13:34:55 +0200642 # -h
643 # -c value
644 # --config value
645 # --help
646 # --health-check
tiernoa9843d82018-10-24 10:44:20 +0200647 opts, args = getopt.getopt(sys.argv[1:], "hc:", ["config=", "help", "health-check"])
tierno275411e2018-05-16 14:33:32 +0200648 # TODO add "log-socket-host=", "log-socket-port=", "log-file="
649 config_file = None
650 for o, a in opts:
651 if o in ("-h", "--help"):
652 usage()
653 sys.exit()
654 elif o in ("-c", "--config"):
655 config_file = a
tiernoa9843d82018-10-24 10:44:20 +0200656 elif o == "--health-check":
tierno94f06112020-02-11 12:38:19 +0000657 from osm_lcm.lcm_hc import health_check
658 health_check(health_check_file, Lcm.ping_interval_pace)
tierno275411e2018-05-16 14:33:32 +0200659 # elif o == "--log-socket-port":
660 # log_socket_port = a
661 # elif o == "--log-socket-host":
662 # log_socket_host = a
663 # elif o == "--log-file":
664 # log_file = a
665 else:
666 assert False, "Unhandled option"
quilesj7e13aeb2019-10-08 13:34:55 +0200667
tierno275411e2018-05-16 14:33:32 +0200668 if config_file:
669 if not path.isfile(config_file):
quilesj7e13aeb2019-10-08 13:34:55 +0200670 print("configuration file '{}' does not exist".format(config_file), file=sys.stderr)
tierno275411e2018-05-16 14:33:32 +0200671 exit(1)
672 else:
673 for config_file in (__file__[:__file__.rfind(".")] + ".cfg", "./lcm.cfg", "/etc/osm/lcm.cfg"):
674 if path.isfile(config_file):
675 break
676 else:
tierno17a612f2018-10-23 11:30:42 +0200677 print("No configuration file 'lcm.cfg' found neither at local folder nor at /etc/osm/", file=sys.stderr)
tierno275411e2018-05-16 14:33:32 +0200678 exit(1)
679 lcm = Lcm(config_file)
tierno3e359b12019-02-03 02:29:13 +0100680 lcm.start()
tierno22f4f9c2018-06-11 18:53:39 +0200681 except (LcmException, getopt.GetoptError) as e:
tierno275411e2018-05-16 14:33:32 +0200682 print(str(e), file=sys.stderr)
683 # usage()
684 exit(1)