+ self.refresh_tasks = []
+ """Contains time ordered task list for refreshing the status of VIM VMs and nets"""
+
+ self.pending_tasks = []
+ """Contains time ordered task list for creation, deletion of VIM VMs and nets"""
+
+ self.grouped_tasks = {}
+ """ It contains all the creation/deletion pending tasks grouped by its concrete vm, net, etc
+ <item><item_id>:
+ - <task1> # e.g. CREATE task
+ <task2> # e.g. DELETE task
+ """
+
+ def _reload_vim_actions(self):
+ """
+ Read actions from database and reload them at memory. Fill self.refresh_list, pending_list, vim_actions
+ :return: None
+ """
+ action_completed = False
+ task_list = []
+ old_action_key = None
+
+ with self.db_lock:
+ vim_actions = self.db.get_rows(FROM="vim_actions",
+ WHERE={"datacenter_vim_id": self.datacenter_tenant_id },
+ ORDER_BY=("item", "item_id", "created_at",))
+ for task in vim_actions:
+ item = task["item"]
+ item_id = task["item_id"]
+ action_key = item + item_id
+ if old_action_key != action_key:
+ if not action_completed and task_list:
+ # This will fill needed task parameters into memory, and insert the task if needed in
+ # self.pending_tasks or self.refresh_tasks
+ self._insert_pending_tasks(task_list)
+ task_list = []
+ old_action_key = action_key
+ action_completed = False
+ elif action_completed:
+ continue
+
+ if task["status"] == "SCHEDULED" or task["action"] == "CREATE" or task["action"] == "FIND":
+ task_list.append(task)
+ elif task["action"] == "DELETE":
+ # action completed because deleted and status is not SCHEDULED. Not needed anything
+ action_completed = True
+
+ # Last actions group need to be inserted too
+ if not action_completed and task_list:
+ self._insert_pending_tasks(task_list)
+
+ def _refres_elements(self):
+ """Call VIM to get VMs and networks status until 10 elements"""
+ now = time.time()
+ nb_processed = 0
+ vm_to_refresh_list = []
+ net_to_refresh_list = []
+ vm_to_refresh_dict = {}
+ net_to_refresh_dict = {}
+ items_to_refresh = 0
+ while self.refresh_tasks:
+ task = self.refresh_tasks[0]
+ with self.task_lock:
+ if task['status'] == 'SUPERSEDED':
+ self.refresh_tasks.pop(0)
+ continue
+ if task['modified_at'] > now:
+ break
+ # task["status"] = "processing"
+ nb_processed += 1
+ self.refresh_tasks.pop(0)
+ if task["item"] == 'instance_vms':
+ vm_to_refresh_list.append(task["vim_id"])
+ vm_to_refresh_dict[task["vim_id"]] = task
+ elif task["item"] == 'instance_nets':
+ net_to_refresh_list.append(task["vim_id"])
+ net_to_refresh_dict[task["vim_id"]] = task
+ else:
+ error_text = "unknown task {}".format(task["item"])
+ self.logger.error(error_text)
+ items_to_refresh += 1
+ if items_to_refresh == 10:
+ break
+
+ if vm_to_refresh_list:
+ try:
+ now = time.time()
+ vim_dict = self.vim.refresh_vms_status(vm_to_refresh_list)
+ for vim_id, vim_info in vim_dict.items():
+ # look for task
+ task_need_update = False
+ task = vm_to_refresh_dict[vim_id]
+ self.logger.debug("get-vm vm_id=%s result=%s", task["vim_id"], str(vim_info))
+
+ # update database
+ task_vim_info = task.get("vim_info")
+ task_error_msg = task.get("error_msg")
+ task_vim_status = task["extra"].get("vim_status")
+ if vim_info.get("error_msg"):
+ vim_info["error_msg"] = self._format_vim_error_msg(vim_info["error_msg"])
+ if task_vim_status != vim_info["status"] or task_error_msg != vim_info.get("error_msg") or \
+ task_vim_info != vim_info.get("vim_info"):
+ with self.db_lock:
+ temp_dict = {"status": vim_info["status"],
+ "error_msg": vim_info.get("error_msg"),
+ "vim_info": vim_info.get("vim_info")}
+ self.db.update_rows('instance_vms', UPDATE=temp_dict, WHERE={"uuid": task["item_id"]})
+ task["extra"]["vim_status"] = vim_info["status"]
+ task["error_msg"] = vim_info.get("error_msg")
+ task["vim_info"] = vim_info.get("vim_info")
+ task_need_update = True
+ for interface in vim_info.get("interfaces", ()):
+ vim_interface_id = interface["vim_interface_id"]
+ if vim_interface_id not in task["extra"]["interfaces"]:
+ self.logger.critical("Interface not found {} on task info {}".format(
+ vim_interface_id, task["extra"]["interfaces"]), exc_info=True)
+ continue
+ task_interface = task["extra"]["interfaces"][vim_interface_id]
+ task_vim_interface = task["vim_interfaces"].get(vim_interface_id)
+ if task_vim_interface != interface:
+ # delete old port
+ if task_interface.get("sdn_port_id"):
+ try:
+ with self.db_lock:
+ self.ovim.delete_port(task_interface["sdn_port_id"])
+ task_interface["sdn_port_id"] = None
+ task_need_update = True
+ except ovimException as e:
+ self.logger.error("ovimException deleting external_port={} ".format(
+ task_interface["sdn_port_id"]) + str(e), exc_info=True)
+ # TODO Set error_msg at instance_nets
+
+ # Create SDN port
+ sdn_net_id = task_interface.get("sdn_net_id")
+ if sdn_net_id and interface.get("compute_node") and interface.get("pci"):
+ sdn_port_name = sdn_net_id + "." + task["vim_id"]
+ sdn_port_name = sdn_port_name[:63]
+ try:
+ with self.db_lock:
+ sdn_port_id = self.ovim.new_external_port(
+ {"compute_node": interface["compute_node"],
+ "pci": interface["pci"],
+ "vlan": interface.get("vlan"),
+ "net_id": sdn_net_id,
+ "region": self.vim["config"]["datacenter_id"],
+ "name": sdn_port_name,
+ "mac": interface.get("mac_address")})
+ task_interface["sdn_port_id"] = sdn_port_id
+ task_need_update = True
+ except (ovimException, Exception) as e:
+ self.logger.error(
+ "ovimException creating new_external_port compute_node={} "
+ "pci={} vlan={} ".format(
+ interface["compute_node"],
+ interface["pci"],
+ interface.get("vlan")) + str(e),
+ exc_info=True)
+ # TODO Set error_msg at instance_nets
+ with self.db_lock:
+ self.db.update_rows(
+ 'instance_interfaces',
+ UPDATE={"mac_address": interface.get("mac_address"),
+ "ip_address": interface.get("ip_address"),
+ "vim_info": interface.get("vim_info"),
+ "sdn_port_id": task_interface.get("sdn_port_id"),
+ "compute_node": interface.get("compute_node"),
+ "pci": interface.get("pci"),
+ "vlan": interface.get("vlan"),
+ },
+ WHERE={'uuid': task_interface["iface_id"]})
+ task["vim_interfaces"][vim_interface_id] = interface
+ if task_need_update:
+ with self.db_lock:
+ self.db.update_rows(
+ 'vim_actions',
+ UPDATE={"extra": yaml.safe_dump(task["extra"], default_flow_style=True, width=256),
+ "error_msg": task.get("error_msg"), "modified_at": now},
+ WHERE={'instance_action_id': task['instance_action_id'],
+ 'task_index': task['task_index']})
+ if task["extra"].get("vim_status") == "BUILD":
+ self._insert_refresh(task, now + self.REFRESH_BUILD)
+ else:
+ self._insert_refresh(task, now + self.REFRESH_ACTIVE)
+ except vimconn.vimconnException as e:
+ self.logger.error("vimconnException Exception when trying to refresh vms " + str(e))
+ self._insert_refresh(task, now + self.REFRESH_ACTIVE)
+
+ if net_to_refresh_list:
+ try:
+ now = time.time()
+ vim_dict = self.vim.refresh_nets_status(net_to_refresh_list)
+ for vim_id, vim_info in vim_dict.items():
+ # look for task
+ task = net_to_refresh_dict[vim_id]
+ self.logger.debug("get-net net_id=%s result=%s", task["vim_id"], str(vim_info))
+
+ task_vim_info = task.get("vim_info")
+ task_vim_status = task["extra"].get("vim_status")
+ task_error_msg = task.get("error_msg")
+ task_sdn_net_id = task["extra"].get("sdn_net_id")
+
+ # get ovim status
+ if task_sdn_net_id:
+ try:
+ with self.db_lock:
+ sdn_net = self.ovim.show_network(task_sdn_net_id)
+ if sdn_net["status"] == "ERROR":
+ if not vim_info.get("error_msg"):
+ vim_info["error_msg"] = sdn_net["error_msg"]
+ else:
+ vim_info["error_msg"] = "VIM_ERROR: {} && SDN_ERROR: {}".format(
+ self._format_vim_error_msg(vim_info["error_msg"], 1024//2-14),
+ self._format_vim_error_msg(sdn_net["error_msg"], 1024//2-14))
+ if vim_info["status"] == "VIM_ERROR":
+ vim_info["status"] = "VIM_SDN_ERROR"
+ else:
+ vim_info["status"] = "SDN_ERROR"
+
+ except (ovimException, Exception) as e:
+ self.logger.error(
+ "ovimException getting network infor snd_net_id={}".format(task_sdn_net_id),
+ exc_info=True)
+ # TODO Set error_msg at instance_nets
+
+ # update database
+ if vim_info.get("error_msg"):
+ vim_info["error_msg"] = self._format_vim_error_msg(vim_info["error_msg"])
+ if task_vim_status != vim_info["status"] or task_error_msg != vim_info.get("error_msg") or \
+ task_vim_info != vim_info["vim_info"]:
+ task["extra"]["vim_status"] = vim_info["status"]
+ task["error_msg"] = vim_info.get("error_msg")
+ task["vim_info"] = vim_info["vim_info"]
+ temp_dict = {"status": vim_info["status"],
+ "error_msg": vim_info.get("error_msg"),
+ "vim_info": vim_info["vim_info"]}
+ with self.db_lock:
+ self.db.update_rows('instance_nets', UPDATE=temp_dict, WHERE={"uuid": task["item_id"]})
+ self.db.update_rows(
+ 'vim_actions',
+ UPDATE={"extra": yaml.safe_dump(task["extra"], default_flow_style=True, width=256),
+ "error_msg": task.get("error_msg"), "modified_at": now},
+ WHERE={'instance_action_id': task['instance_action_id'],
+ 'task_index': task['task_index']})
+ if task["extra"].get("vim_status") == "BUILD":
+ self._insert_refresh(task, now + self.REFRESH_BUILD)
+ else:
+ self._insert_refresh(task, now + self.REFRESH_ACTIVE)
+ except vimconn.vimconnException as e:
+ self.logger.error("vimconnException Exception when trying to refresh nets " + str(e))
+ self._insert_refresh(task, now + self.REFRESH_ACTIVE)
+
+ return nb_processed
+
+ def _insert_refresh(self, task, threshold_time=None):
+ """Insert a task at list of refreshing elements. The refreshing list is ordered by threshold_time (task['modified_at']
+ It is assumed that this is called inside this thread
+ """
+ if not self.vim:
+ return
+ if not threshold_time:
+ threshold_time = time.time()
+ task["modified_at"] = threshold_time
+ task_name = task["item"][9:] + "-" + task["action"]
+ task_id = task["instance_action_id"] + "." + str(task["task_index"])
+ for index in range(0, len(self.refresh_tasks)):
+ if self.refresh_tasks[index]["modified_at"] > threshold_time:
+ self.refresh_tasks.insert(index, task)
+ break
+ else:
+ index = len(self.refresh_tasks)
+ self.refresh_tasks.append(task)
+ self.logger.debug("new refresh task={} name={}, modified_at={} index={}".format(
+ task_id, task_name, task["modified_at"], index))
+
+ def _remove_refresh(self, task_name, vim_id):
+ """Remove a task with this name and vim_id from the list of refreshing elements.
+ It is assumed that this is called inside this thread outside _refres_elements method
+ Return True if self.refresh_list is modified, task is found
+ Return False if not found
+ """
+ index_to_delete = None
+ for index in range(0, len(self.refresh_tasks)):
+ if self.refresh_tasks[index]["name"] == task_name and self.refresh_tasks[index]["vim_id"] == vim_id:
+ index_to_delete = index
+ break
+ else:
+ return False
+ if index_to_delete != None:
+ del self.refresh_tasks[index_to_delete]
+ return True
+
+ def _proccess_pending_tasks(self):
+ nb_created = 0
+ nb_processed = 0
+ while self.pending_tasks:
+ task = self.pending_tasks.pop(0)
+ nb_processed += 1
+ if task["status"] == "SUPERSEDED":
+ # not needed to do anything but update database with the new status
+ result = True
+ database_update = None
+ elif not self.vim:
+ task["status"] == "ERROR"
+ task["error_msg"] = self.error_status
+ result = False
+ database_update = {"status": "VIM_ERROR", "error_msg": task["error_msg"]}
+ elif task["item"] == 'instance_vms':
+ if task["action"] == "CREATE":
+ result, database_update = self.new_vm(task)
+ nb_created += 1
+ elif task["action"] == "DELETE":
+ result, database_update = self.del_vm(task)
+ else:
+ raise vimconn.vimconnException(self.name + "unknown task action {}".format(task["action"]))
+ elif task["item"] == 'instance_nets':
+ if task["action"] == "CREATE":
+ result, database_update = self.new_net(task)
+ nb_created += 1
+ elif task["action"] == "DELETE":
+ result, database_update = self.del_net(task)
+ elif task["action"] == "FIND":
+ result, database_update = self.get_net(task)
+ else:
+ raise vimconn.vimconnException(self.name + "unknown task action {}".format(task["action"]))
+ else:
+ raise vimconn.vimconnException(self.name + "unknown task item {}".format(task["item"]))
+ # TODO
+
+ if task["status"] == "SCHEDULED":
+ # This is because a depend task is not completed. Moved to the end. NOT USED YET
+ if task["extra"].get("tries", 0) > 3:
+ task["status"] == "FAILED"
+ else:
+ task["extra"]["tries"] = task["extra"].get("tries", 0) + 1
+ self.pending_tasks.append(task)
+ elif task["action"] == "DELETE":
+ action_key = task["item"] + task["item_id"]
+ del self.grouped_tasks[action_key]
+ elif task["action"] in ("CREATE", "FIND") and task["status"] in ("DONE", "BUILD"):
+ self._insert_refresh(task)
+
+ self.logger.debug("vim_action id={}.{} item={} action={} result={}:{} params={}".format(
+ task["instance_action_id"], task["task_index"], task["item"], task["action"],
+ task["status"], task["vim_id"] if task["status"] == "DONE" else task.get("error_msg"),
+ task["params"]))
+ try:
+ now = time.time()
+ with self.db_lock:
+ self.db.update_rows(
+ table="vim_actions",
+ UPDATE={"status": task["status"], "vim_id": task["vim_id"], "modified_at": now,
+ "error_msg": task["error_msg"],
+ "extra": yaml.safe_dump(task["extra"], default_flow_style=True, width=256)},
+ WHERE={"instance_action_id": task["instance_action_id"], "task_index": task["task_index"]})
+ if result is not None:
+ self.db.update_rows(
+ table="instance_actions",
+ UPDATE={("number_done" if result else "number_failed"): {"INCREMENT": 1},
+ "modified_at": now},
+ WHERE={"uuid": task["instance_action_id"]})
+ if database_update:
+ self.db.update_rows(table=task["item"],
+ UPDATE=database_update,
+ WHERE={"uuid": task["item_id"]})
+ except db_base_Exception as e:
+ self.logger.error("Error updating database %s", str(e), exc_info=True)
+
+ if nb_created == 10:
+ break
+ return nb_processed
+
+ def _insert_pending_tasks(self, vim_actions_list):
+ now = time.time()
+ for task in vim_actions_list:
+ if task["datacenter_vim_id"] != self.datacenter_tenant_id:
+ continue
+ item = task["item"]
+ item_id = task["item_id"]
+ action_key = item + item_id
+ if action_key not in self.grouped_tasks:
+ self.grouped_tasks[action_key] = []
+ task["params"] = None
+ task["depends"] = {}
+ if task["extra"]:
+ extra = yaml.load(task["extra"])
+ task["extra"] = extra
+ task["params"] = extra.get("params")
+ depends_on_list = extra.get("depends_on")
+ if depends_on_list:
+ for index in depends_on_list:
+ if index < len(vim_actions_list) and vim_actions_list[index]["task_index"] == index and\
+ vim_actions_list[index]["instance_action_id"] == task["instance_action_id"]:
+ task["depends"]["TASK-" + str(index)] = vim_actions_list[index]
+ if extra.get("interfaces"):
+ task["vim_interfaces"] = {}
+ else:
+ task["extra"] = {}
+ if "error_msg" not in task:
+ task["error_msg"] = None
+ if "vim_id" not in task:
+ task["vim_id"] = None
+
+ if task["action"] == "DELETE":
+ need_delete_action = False
+ for to_supersede in self.grouped_tasks.get(action_key, ()):
+ if to_supersede["action"] == "FIND" and to_supersede.get("vim_id"):
+ task["vim_id"] = to_supersede["vim_id"]
+ if to_supersede["action"] == "CREATE" and to_supersede.get("vim_id") and \
+ to_supersede["extra"].get("created", True):
+ need_delete_action = True
+ task["vim_id"] = to_supersede["vim_id"]
+ if to_supersede["extra"].get("sdn_vim_id"):
+ task["extra"]["sdn_vim_id"] = to_supersede["extra"]["sdn_vim_id"]
+ if to_supersede["extra"].get("interfaces"):
+ task["extra"]["interfaces"] = to_supersede["extra"]["interfaces"]
+ # Mark task as SUPERSEDED.
+ # If task is in self.pending_tasks, it will be removed and database will be update
+ # If task is in self.refresh_tasks, it will be removed
+ to_supersede["status"] = "SUPERSEDED"
+ if not need_delete_action:
+ task["status"] = "SUPERSEDED"
+
+ self.grouped_tasks[action_key].append(task)
+ self.pending_tasks.append(task)
+ elif task["status"] == "SCHEDULED":
+ self.grouped_tasks[action_key].append(task)
+ self.pending_tasks.append(task)
+ elif task["action"] in ("CREATE", "FIND"):
+ self.grouped_tasks[action_key].append(task)
+ if task["status"] in ("DONE", "BUILD"):
+ self._insert_refresh(task)
+ # TODO add VM reset, get console, etc...
+ else:
+ raise vimconn.vimconnException(self.name + "unknown vim_action action {}".format(task["action"]))
+