path: root/vnftest/core/task.py

##############################################################################
# Copyright 2018 EuropeanSoftwareMarketingLtd.
# ===================================================================
#  Licensed under the ApacheLicense, Version2.0 (the"License");
#  you may not use this file except in compliance with the License.
#  You may obtain a copy of the License at
#  http://www.apache.org/licenses/LICENSE-2.0
#
# software distributed under the License is distributed on an "AS IS" BASIS,
# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
# See the License for the specific language governing permissions and limitations under
# the License
##############################################################################
# vnftest comment: this is a modified copy of
# yardstick/benchmark/core/task.py

""" Handler for vnftest command 'task' """

from __future__ import absolute_import
from __future__ import print_function

import atexit
import collections
import copy
import logging
import sys
import time
import uuid

import ipaddress
import os
import yaml
from jinja2 import Environment
from six.moves import filter
from vnftest.runners import base as base_runner

from vnftest.contexts.base import Context
from vnftest.contexts.csar import CSARContext
from vnftest.runners import base as base_runner
from vnftest.runners.duration import DurationRunner
from vnftest.runners.iteration import IterationRunner
from vnftest.common.constants import CONF_FILE
from vnftest.common.html_template import report_template
from vnftest.common.task_template import TaskTemplate
from vnftest.common.yaml_loader import yaml_load
from vnftest.contexts.base import Context
from vnftest.dispatcher.base import Base as DispatcherBase
from vnftest.common.task_template import TaskTemplate
from vnftest.common import utils
from vnftest.common import constants
from vnftest.common.html_template import report_template

output_file_default = "/tmp/vnftest.out"
LOG = logging.getLogger(__name__)


class Task(object):     # pragma: no cover
    """Task commands.

       Set of commands to manage benchmark tasks.
    """

    def __init__(self, args):
        self.context = None
        self.outputs = {}
        self.args = args or {}
        task_id = getattr(args, 'task_id', None)
        self.task_id = task_id if task_id is not None else str(uuid.uuid4())
        self.task_info = TaskInfo(task_id)

    def _set_dispatchers(self, output_config):
        dispatchers = output_config.get('DEFAULT', {}).get('dispatcher',
                                                           'file')
        out_types = [s.strip() for s in dispatchers.split(',')]
        output_config['DEFAULT']['dispatcher'] = out_types

    def start(self):
        atexit.register(self.atexit_handler)
        self._set_log()
        try:
            output_config = utils.parse_ini_file(CONF_FILE)
        except Exception:
            # all error will be ignore, the default value is {}
            output_config = {}

        self._init_output_config(output_config)
        self._set_output_config(output_config, self.args.output_file)
        LOG.debug('Output configuration is: %s', output_config)

        self._set_dispatchers(output_config)

        # update dispatcher list
        if 'file' in output_config['DEFAULT']['dispatcher']:
            result = {'status': 0, 'result': {}}
            utils.write_json_to_file(self.args.output_file, result)

        total_start_time = time.time()
        parser = TaskParser(self.args.inputfile)

        if self.args.suite:
            # 1.parse suite, return suite_params info
            task_files, task_args_list, task_args_fnames = parser.parse_suite()

        else:
            task_files = [parser.path]
            task_args_list = [{}]
            task_args_fnames = [self.args.task_args_file]

        LOG.debug("task_files:%s, task_args_list:%s, task_args_fnames:%s",
                  task_files, task_args_list, task_args_fnames)

        if self.args.parse_only:
            sys.exit(0)

        try:
            for i in range(0, len(task_files)):
                one_task_start_time = time.time()
                # the output of the previous task is the input of the new task
                inputs = copy.deepcopy(self.outputs)
                task_args_file = task_args_fnames[i]
                task_args = task_args_list[i]
                try:
                    inputs.update(parse_task_args("global_task_args", self.args.task_args))
                    if task_args_file:
                        with utils.load_resource(task_args_file) as f:
                            inputs.update(parse_task_args("task_args_file", f.read()))
                    # task args from suite may override file args.
                    inputs.update(parse_task_args("task_args", task_args))
                except TypeError:
                    raise TypeError()
                parser.path = task_files[i]
                steps, run_in_parallel, meet_precondition, ret_context = \
                    parser.parse_task(self.task_id, inputs)

                self.context = ret_context

                if not meet_precondition:
                    LOG.info("meet_precondition is %s, please check envrionment",
                             meet_precondition)
                    continue

                case_name = os.path.splitext(os.path.basename(task_files[i]))[0]
                try:
                    self._run(steps, case_name, run_in_parallel, self.args.output_file, inputs)
                except KeyboardInterrupt:
                    raise
                except Exception:
                    LOG.error('Testcase: "%s" FAILED!!!', case_name, exc_info=True)

                if self.args.keep_deploy:
                    # keep deployment, forget about stack
                    # (hide it for exit handler)
                    self.context = None
                else:
                    self.context.undeploy()
                    self.context = None
                one_task_end_time = time.time()
                LOG.info("Task %s finished in %d secs", task_files[i],
                         one_task_end_time - one_task_start_time)
        except Exception as e:
            LOG.error("Task fatal error: %s", e)
            self.task_info.task_fatal()
        finally:
            self.task_info.task_end()

        self._do_output(output_config)
        self._generate_reporting()

        total_end_time = time.time()
        LOG.info("Total finished in %d secs",
                 total_end_time - total_start_time)

        step = steps[0]
        LOG.info("To generate report, execute command "
                 "'vnftest report generate %(task_id)s %(tc)s'", step)
        LOG.info("Task ALL DONE, exiting")
        return self.task_info.result()

    def _generate_reporting(self):
        env = Environment()
        file_name = 'report_' + str(self.task_id) + '.html'
        report_file = os.path.join(constants.REPORT_DIR, file_name)
        with open(report_file, 'w') as f:
            f.write(env.from_string(report_template).render(self.task_info.result()))

        LOG.info("Report can be found in '%s'", report_file)

    def _set_log(self):
        log_format = '%(asctime)s %(name)s %(filename)s:%(lineno)d %(levelname)s %(message)s'
        log_formatter = logging.Formatter(log_format)

        utils.makedirs(constants.TASK_LOG_DIR)
        log_path = os.path.join(constants.TASK_LOG_DIR, '{}.log'.format(self.task_id))
        log_handler = logging.FileHandler(log_path)
        log_handler.setFormatter(log_formatter)
        log_handler.setLevel(logging.DEBUG)

        logging.root.addHandler(log_handler)

    def _init_output_config(self, output_config):
        output_config.setdefault('DEFAULT', {})
        output_config.setdefault('dispatcher_http', {})
        output_config.setdefault('dispatcher_file', {})
        output_config.setdefault('dispatcher_influxdb', {})

    def _set_output_config(self, output_config, file_path):
        try:
            out_type = os.environ['DISPATCHER']
        except KeyError:
            output_config['DEFAULT'].setdefault('dispatcher', 'file')
        else:
            output_config['DEFAULT']['dispatcher'] = out_type

        output_config['dispatcher_file']['file_path'] = file_path

        try:
            target = os.environ['TARGET']
        except KeyError:
            pass
        else:
            k = 'dispatcher_{}'.format(output_config['DEFAULT']['dispatcher'])
            output_config[k]['target'] = target

    def _do_output(self, output_config):
        dispatchers = DispatcherBase.get(output_config)

        for dispatcher in dispatchers:
            dispatcher.flush_result_data(self.task_id, self.task_info.result())

    def _run(self, steps, case_name, run_in_parallel, output_file, inputs):
        """Deploys context and calls runners"""
        if self.context:
            self.context.deploy()
        try:
            self.task_info.testcase_start(case_name)
            for step in steps:
                step_unique_id = self.task_info.step_add(case_name, step['name'])
                step['step_unique_id'] = step_unique_id

            background_runners = []
            result = []
            # Start all background steps
            for step in filter(_is_background_step, steps):
                step["runner"] = dict(type="Duration", duration=1000000000)
                self.task_info.step_start(step['step_unique_id'])
                runner = self.run_one_step(step, output_file, inputs)
                background_runners.append([step, runner])

            runners = []
            if run_in_parallel:
                for step in steps:
                    if not _is_background_step(step):
                        self.task_info.step_start(step['step_unique_id'])
                        runner = self.run_one_step(step, output_file, inputs)
                        runners.append([step, runner])

                # Wait for runners to finish
                for runner_item in runners:
                    self.finalize_step(runner_item[0], runner_item[1], result)
            else:
                # run serially
                for step in steps:
                    if not _is_background_step(step):
                        self.task_info.step_start(step['step_unique_id'])
                        runner = self.run_one_step(step, output_file, inputs)
                        self.finalize_step(step, runner, result)

            # Abort background runners
            for runner_item in background_runners:
                runner_item[1].abort()

            # Wait for background runners to finish
            for runner_item in background_runners:
                runner = runner_item[1]
                self.finalize_step(step, runner, result)
            return result
        except Exception as e:
            LOG.exception('Case fatal error: %s', e)
            self.task_info.testcase_fatal(case_name)
        finally:
            self.task_info.testcase_end(case_name)

    def atexit_handler(self):
        """handler for process termination"""
        base_runner.Runner.terminate_all()

        if self.context:
            LOG.info("Undeploying context")
            self.context.undeploy()

    def _parse_options(self, op):
        if isinstance(op, dict):
            return {k: self._parse_options(v) for k, v in op.items()}
        elif isinstance(op, list):
            return [self._parse_options(v) for v in op]
        elif isinstance(op, str):
            return self.outputs.get(op[1:]) if op.startswith('$') else op
        else:
            return op

    def run_one_step(self, step_cfg, output_file, inputs):
        """run one step using context"""
        # default runner is Iteration
        if 'runner' not in step_cfg:
            step_cfg['runner'] = dict(type="Iteration", iterations=1)
        runner_cfg = step_cfg['runner']
        runner_cfg['output_filename'] = output_file
        options = step_cfg.get('options', {})
        step_cfg['options'] = self._parse_options(options)
        runner = base_runner.Runner.get(runner_cfg)

        LOG.info("Starting runner of type '%s'", runner_cfg["type"])
        # Previous steps output is the input of the next step.
        inputs.update(self.outputs)
        runner.run(step_cfg, self.context, inputs)
        return runner

    def finalize_step(self, step, runner, result):
        step_result_list = []
        status = runner_join(runner, self.outputs, step_result_list)
        self.task_info.step_end(step['step_unique_id'], step_result_list)
        if status != 0:
            raise RuntimeError(
                "{0} runner status {1}".format(runner.__execution_type__, status))
        LOG.info("Runner ended")
        result.extend(step_result_list)


class TaskParser(object):       # pragma: no cover
    """Parser for task config files in yaml format"""

    def __init__(self, path):
        self.path = path

    def _meet_constraint(self, task, cur_pod, cur_installer):
        if "constraint" in task:
            constraint = task.get('constraint', None)
            if constraint is not None:
                tc_fit_pod = constraint.get('pod', None)
                tc_fit_installer = constraint.get('installer', None)
                LOG.info("cur_pod:%s, cur_installer:%s,tc_constraints:%s",
                         cur_pod, cur_installer, constraint)
                if (cur_pod is None) or (tc_fit_pod and cur_pod not in tc_fit_pod):
                    return False
                if (cur_installer is None) or (tc_fit_installer and cur_installer
                                               not in tc_fit_installer):
                    return False
        return True

    def _get_task_para(self, task, cur_pod):
        task_args = task.get('task_args', None)
        if task_args is not None:
            task_args = task_args.get(cur_pod, task_args.get('default'))
        task_args_fnames = task.get('task_args_fnames', None)
        if task_args_fnames is not None:
            task_args_fnames = task_args_fnames.get(cur_pod, None)
        return task_args, task_args_fnames

    def parse_suite(self):
        """parse the suite file and return a list of task config file paths
           and lists of optional parameters if present"""
        LOG.info("\nParsing suite file:%s", self.path)

        try:
            with utils.load_resource(self.path) as stream:
                cfg = yaml_load(stream)
        except IOError as ioerror:
            LOG.error("Open suite file failed", ioerror)
            raise

        self._check_schema(cfg["schema"], "suite")
        LOG.info("\nStarting step:%s", cfg["name"])

        cur_pod = os.environ.get('NODE_NAME', "default")
        cur_installer = os.environ.get('INSTALLER_TYPE', None)

        valid_task_files = []
        valid_task_args = []
        valid_task_args_fnames = []

        for task in cfg["test_cases"]:
            # 1.check file_name
            if "file_name" in task:
                task_fname = task.get('file_name', None)
                if task_fname is None:
                    continue
            else:
                continue
            # 2.check constraint
            if self._meet_constraint(task, cur_pod, cur_installer):
                valid_task_files.append(task_fname)
            else:
                continue
            # 3.fetch task parameters
            task_args, task_args_fnames = self._get_task_para(task, cur_pod)
            valid_task_args.append(task_args)
            valid_task_args_fnames.append(task_args_fnames)

        return valid_task_files, valid_task_args, valid_task_args_fnames

    def parse_task(self, task_id, inputs=None):
        """parses the task file and return an context and step instances"""
        LOG.info("Parsing task config: %s", self.path)
        kw = inputs
        try:
            with utils.load_resource(self.path) as f:
                try:
                    input_task = f.read()
                    rendered_task = TaskTemplate.render(input_task, **kw)
                except Exception as e:
                    LOG.exception('Failed to render template:\n%s\n', input_task)
                    raise e
                LOG.debug("Input task is:\n%s\n", rendered_task)

                cfg = yaml_load(rendered_task)
        except IOError as ioerror:
            sys.exit(ioerror)

        self._check_schema(cfg["schema"], "task")
        meet_precondition = self._check_precondition(cfg)

        if "context" in cfg:
            context_cfg = cfg["context"]
        else:
            context_cfg = {"type": "Dummy"}

        name_suffix = '-{}'.format(task_id[:8])
        try:
            context_cfg['name'] = '{}{}'.format(context_cfg['name'],
                                                name_suffix)
        except KeyError:
            pass
        # default to CSAR context
        context_type = context_cfg.get("type", "CSAR")
        context = Context.get(context_type)
        context.init(context_cfg)

        run_in_parallel = cfg.get("run_in_parallel", False)

        # add tc and task id for influxdb extended tags
        for step in cfg["steps"]:
            task_name = os.path.splitext(os.path.basename(self.path))[0]
            step["tc"] = task_name
            step["task_id"] = task_id
            # embed task path into step so we can load other files
            # relative to task path
            step["task_path"] = os.path.dirname(self.path)

        # TODO we need something better here, a class that represent the file
        return cfg["steps"], run_in_parallel, meet_precondition, context

    def _check_schema(self, cfg_schema, schema_type):
        """Check if config file is using the correct schema type"""

        if cfg_schema != "vnftest:" + schema_type + ":0.1":
            sys.exit("error: file %s has unknown schema %s" % (self.path,
                                                               cfg_schema))

    def _check_precondition(self, cfg):
        """Check if the environment meet the precondition"""

        if "precondition" in cfg:
            precondition = cfg["precondition"]
            installer_type = precondition.get("installer_type", None)
            tc_fit_pods = precondition.get("pod_name", None)
            installer_type_env = os.environ.get('INSTALL_TYPE', None)
            pod_name_env = os.environ.get('NODE_NAME', None)

            LOG.info("installer_type: %s, installer_type_env: %s",
                     installer_type, installer_type_env)
            LOG.info("tc_fit_pods: %s, pod_name_env: %s",
                     tc_fit_pods, pod_name_env)
            if installer_type and installer_type_env:
                if installer_type_env not in installer_type:
                    return False
                return False
            if tc_fit_pods and pod_name_env:
                if pod_name_env not in tc_fit_pods:
                    return False
        return True


class TaskInfo(object):

    def __init__(self, task_id):
        self.info_dict = {'task_id': task_id, 'status': 'IN_PROGRESS', 'criteria': 'N/A'}
        self.result_info_dict = {}
        self.info_dict['info'] = self.result_info_dict
        self.test_cases_list = []
        self.info_dict['testcases'] = self.test_cases_list
        self.helper_test_cases_dict = {}
        self.helper_test_steps_dict = {}
        self.step_id_helper = 0

    def task_end(self):
        if self.info_dict['criteria'] == 'N/A':
            criteria = 'PASS'
            for testcase in self.info_dict['testcases']:
                if testcase['criteria'] == 'FAIL' or testcase['criteria'] == 'FATAL':
                    criteria = 'FAIL'
                    break
            self.info_dict['criteria'] = criteria
            self.info_dict['status'] = 'FINISHED'

    def task_fatal(self):
        self.info_dict['criteria'] = 'FATAL'
        self.info_dict['status'] = 'FINISHED'

    def testcase_fatal(self, testcase_name):
        testcase_dict = self.helper_test_cases_dict[testcase_name]
        testcase_dict['criteria'] = 'FATAL'
        testcase_dict['status'] = 'FINISHED'

    def testcase_start(self, testcase_name):
        testcase_dict = {'name': testcase_name, 'criteria': 'N/A', 'status': 'IN_PROGRESS', 'steps': []}
        self.test_cases_list.append(testcase_dict)
        self.helper_test_cases_dict[testcase_name] = testcase_dict

    def testcase_end(self, testcase_name):
        testcase_dict = self.helper_test_cases_dict[testcase_name]
        if testcase_dict['criteria'] == 'N/A':
            criteria = 'PASS'
            for step in testcase_dict['steps']:
                if step['criteria'] == 'FAIL':
                    criteria = 'FAIL'
                    break
            testcase_dict['criteria'] = criteria
            testcase_dict['status'] = 'FINISHED'

    def step_add(self, testcase_name, step_name):
        step_dict = {'name': step_name, 'criteria': 'N/A', 'status': 'NOT_STARTED', 'results': []}
        testcase_dict = self.helper_test_cases_dict[testcase_name]
        testcase_dict['steps'].append(step_dict)
        self.step_id_helper += 1
        step_unique_id = step_name + '_' + str(self.step_id_helper)
        self.helper_test_steps_dict[step_unique_id] = step_dict
        return step_unique_id

    def step_start(self, step_unique_id):
        step_dict = self.helper_test_steps_dict[step_unique_id]
        step_dict['status'] = 'IN_PROGRESS'

    def step_end(self, step_unique_id, result_list):
        step_dict = self.helper_test_steps_dict[step_unique_id]
        errors_count = 0
        for result in result_list:
            result_item = {
                'timestamp': result['timestamp'],
                'sequence': result['sequence'],
                'data': [],
                'errors': []
            }
            for k, v in result['data'].items():
                result_item['data'].append({'type': 'String', 'key': k, 'value': str(v)})

            for error in result['errors']:
                result_item['errors'].append({'type': 'String', 'key': 'error', 'value': str(error)})
                errors_count += 1
            step_dict['results'].append(result_item)
        if errors_count > 0:
            step_dict['criteria'] = 'FAIL'
        else:
            step_dict['criteria'] = 'PASS'
        step_dict['status'] = 'FINISHED'

    def result(self):
        return copy.deepcopy(self.info_dict)


def is_ip_addr(addr):
    """check if string addr is an IP address"""
    try:
        addr = addr.get('public_ip_attr', addr.get('private_ip_attr'))
    except AttributeError:
        pass

    try:
        ipaddress.ip_address(addr.encode('utf-8'))
    except ValueError:
        return False
    else:
        return True


def _is_background_step(step):
    if "run_in_background" in step:
        return step["run_in_background"]
    else:
        return False


def runner_join(runner, outputs, result):
    while runner.poll() is None:
        outputs.update(runner.get_output())
        result.extend(runner.get_result())
    status = runner.join(outputs, result)
    base_runner.Runner.release(runner)
    return status


def print_invalid_header(source_name, args):
    print("Invalid %(source)s passed:\n\n %(args)s\n"
          % {"source": source_name, "args": args})


def parse_task_args(src_name, args):
    if isinstance(args, collections.Mapping):
        return args

    try:
        kw = args and yaml_load(args)
        kw = {} if kw is None else kw
    except yaml.parser.ParserError as e:
        print_invalid_header(src_name, args)
        print("%(source)s has to be YAML. Details:\n\n%(err)s\n"
              % {"source": src_name, "err": e})
        raise TypeError()

    if not isinstance(kw, dict):
        print_invalid_header(src_name, args)
        print("%(src)s had to be dict, actually %(src_type)s\n"
              % {"src": src_name, "src_type": type(kw)})
        raise TypeError()
    return kw