{ "cells": [ { "cell_type": "markdown", "metadata": {}, "source": [ "# Tutorial Goal" ] }, { "cell_type": "markdown", "metadata": {}, "source": [ "This tutorial aims to show how to **configure and run** a predefined set of\n", "**synthetic workload** using the **executor module** provided by LISA." ] }, { "cell_type": "markdown", "metadata": {}, "source": [ "# Configure logging" ] }, { "cell_type": "code", "execution_count": 1, "metadata": { "collapsed": true }, "outputs": [], "source": [ "import logging\n", "from conf import LisaLogging\n", "LisaLogging.setup()" ] }, { "cell_type": "code", "execution_count": 2, "metadata": { "collapsed": false }, "outputs": [], "source": [ "# Execute this cell to enabled executor debugging statements\n", "logging.getLogger('Executor').setLevel(logging.DEBUG)" ] }, { "cell_type": "markdown", "metadata": {}, "source": [ "# Target Configuration" ] }, { "cell_type": "code", "execution_count": 3, "metadata": { "collapsed": false, "scrolled": true }, "outputs": [ { "name": "stderr", "output_type": "stream", "text": [ "2016-11-17 17:00:31,811 INFO : Target - Using base path: /home/bjackman/sources/lisa\n", "2016-11-17 17:00:31,812 INFO : Target - Loading custom (inline) target configuration\n", "2016-11-17 17:00:31,812 DEBUG : Target - Target configuration {'username': 'brendan', 'platform': 'linux', 'host': '192.168.0.1', 'ftrace': {'buffsize': 81920, 'events': ['sched_switch', 'sched_wakeup', 'sched_wakeup_new', 'cpu_frequency']}, 'board': 'juno', 'modules': ['bl', 'cpufreq'], 'password': 'test0000', 'tools': ['trace-cmd', 'perf'], 'results_dir': 'ExecutorExample'}\n", "2016-11-17 17:00:31,813 INFO : Target - Devlib modules to load: ['bl', 'cpufreq', 'hwmon']\n", "2016-11-17 17:00:31,814 INFO : Target - Connecting linux target:\n", "2016-11-17 17:00:31,815 INFO : Target - username : brendan\n", "2016-11-17 17:00:31,816 INFO : Target - host : 192.168.0.1\n", "2016-11-17 17:00:31,817 INFO : Target - password : \n", "2016-11-17 17:00:31,819 INFO : Target - Connection settings:\n", "2016-11-17 17:00:31,820 INFO : Target - {'username': 'brendan', 'host': '192.168.0.1', 'password': ''}\n", "2016-11-17 17:00:31,821 DEBUG : Target - Setup LINUX target...\n", "2016-11-17 17:00:31,828 DEBUG : Installing module vexpress-u-boot\n", "2016-11-17 17:00:31,829 DEBUG : Installing module vexpress-dtr\n", "2016-11-17 17:00:31,830 DEBUG : Installing module vexpress-vemsd\n", "2016-11-17 17:00:31,831 DEBUG : Logging in brendan@192.168.0.1\n", "2016-11-17 17:00:33,311 DEBUG : id\n", "2016-11-17 17:00:33,722 DEBUG : echo $USER\n", "2016-11-17 17:00:34,128 DEBUG : if [ -e '/home/brendan/devlib-target/bin' ]; then echo 1; else echo 0; fi\n", "2016-11-17 17:00:34,534 DEBUG : ls -1 /home/brendan/devlib-target/bin\n", "2016-11-17 17:00:34,944 DEBUG : cat /proc/cpuinfo\n", "2016-11-17 17:00:35,353 DEBUG : sudo -- sh -c 'ls /'\n", "2016-11-17 17:00:35,901 DEBUG : sudo -- sh -c 'dmidecode -s system-version'\n", "2016-11-17 17:00:36,706 DEBUG : Installing module bl\n", "2016-11-17 17:00:36,808 DEBUG : uname -m\n", "2016-11-17 17:00:37,216 DEBUG : if [ -e '/sys/devices/system/cpu/cpufreq' ]; then echo 1; else echo 0; fi\n", "2016-11-17 17:00:37,523 DEBUG : Installing module cpufreq\n", "2016-11-17 17:00:37,624 DEBUG : if [ -e '/sys/class/hwmon' ]; then echo 1; else echo 0; fi\n", "2016-11-17 17:00:37,931 DEBUG : Installing module hwmon\n", "2016-11-17 17:00:38,033 DEBUG : ls -1 /sys/class/hwmon\n", "2016-11-17 17:00:38,442 DEBUG : if [ -e '/sys/class/hwmon/hwmon0/name' ]; then echo 1; else echo 0; fi\n", "2016-11-17 17:00:38,848 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/name'\\'''\n", "2016-11-17 17:00:39,754 DEBUG : ls -1 /sys/class/hwmon/hwmon0/\n", "2016-11-17 17:00:40,166 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/curr1_label'\\'''\n", "2016-11-17 17:00:41,072 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/curr2_label'\\'''\n", "2016-11-17 17:00:41,977 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/curr3_label'\\'''\n", "2016-11-17 17:00:42,882 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/curr4_label'\\'''\n", "2016-11-17 17:00:43,788 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy1_label'\\'''\n", "2016-11-17 17:00:44,694 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_label'\\'''\n", "2016-11-17 17:00:45,599 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_label'\\'''\n", "2016-11-17 17:00:46,505 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy4_label'\\'''\n", "2016-11-17 17:00:47,410 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in0_label'\\'''\n", "2016-11-17 17:00:48,316 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in1_label'\\'''\n", "2016-11-17 17:00:49,221 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in2_label'\\'''\n", "2016-11-17 17:00:50,126 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in3_label'\\'''\n", "2016-11-17 17:00:51,032 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in4_label'\\'''\n", "2016-11-17 17:00:51,938 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in5_label'\\'''\n", "2016-11-17 17:00:52,843 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/in6_label'\\'''\n", "2016-11-17 17:00:53,749 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/power1_label'\\'''\n", "2016-11-17 17:00:54,654 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/power2_label'\\'''\n", "2016-11-17 17:00:55,560 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/power3_label'\\'''\n", "2016-11-17 17:00:56,466 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/power4_label'\\'''\n", "2016-11-17 17:00:57,371 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/temp1_label'\\'''\n", "2016-11-17 17:00:58,277 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/temp2_label'\\'''\n", "2016-11-17 17:00:59,082 DEBUG : Target - Checking target connection...\n", "2016-11-17 17:00:59,083 DEBUG : Target - Target info:\n", "2016-11-17 17:00:59,084 DEBUG : Target - ABI: arm64\n", "2016-11-17 17:00:59,085 DEBUG : Target - CPUs: CpuInfo(['A53', 'A57', 'A57', 'A53', 'A53', 'A53'])\n", "2016-11-17 17:00:59,087 DEBUG : Target - Clusters: [0, 1, 1, 0, 0, 0]\n", "2016-11-17 17:00:59,088 INFO : Target - Initializing target workdir:\n", "2016-11-17 17:00:59,089 INFO : Target - /home/brendan/devlib-target\n", "2016-11-17 17:00:59,190 DEBUG : mkdir -p /home/brendan/devlib-target\n", "2016-11-17 17:00:59,599 DEBUG : mkdir -p /home/brendan/devlib-target/bin\n", "2016-11-17 17:00:59,909 DEBUG : /usr/bin/scp -r /home/bjackman/sources/lisa/libs/devlib/devlib/bin/arm64/busybox brendan@192.168.0.1:/home/brendan/devlib-target/bin/busybox\n", "2016-11-17 17:01:00,410 DEBUG : chmod a+x /home/brendan/devlib-target/bin/busybox\n", "2016-11-17 17:01:00,718 DEBUG : /usr/bin/scp -r /home/bjackman/sources/lisa/libs/devlib/devlib/bin/scripts/shutils brendan@192.168.0.1:/home/brendan/devlib-target/bin/shutils\n", "2016-11-17 17:01:01,202 DEBUG : chmod a+x /home/brendan/devlib-target/bin/shutils\n", "2016-11-17 17:01:01,511 DEBUG : /usr/bin/scp -r /home/bjackman/sources/lisa/tools/arm64/trace-cmd brendan@192.168.0.1:/home/brendan/devlib-target/bin/trace-cmd\n", "2016-11-17 17:01:01,856 DEBUG : chmod a+x /home/brendan/devlib-target/bin/trace-cmd\n", "2016-11-17 17:01:02,164 DEBUG : /usr/bin/scp -r /home/bjackman/sources/lisa/tools/arm64/perf brendan@192.168.0.1:/home/brendan/devlib-target/bin/perf\n", "2016-11-17 17:01:02,549 DEBUG : chmod a+x /home/brendan/devlib-target/bin/perf\n", "2016-11-17 17:01:02,856 DEBUG : Target - Check for module [bl]...\n", "2016-11-17 17:01:02,857 DEBUG : Target - Check for module [cpufreq]...\n", "2016-11-17 17:01:02,858 DEBUG : Target - Check for module [hwmon]...\n", "2016-11-17 17:01:02,860 INFO : Target - Topology:\n", "2016-11-17 17:01:02,861 INFO : Target - [[0, 3, 4, 5], [1, 2]]\n", "2016-11-17 17:01:02,962 DEBUG : sudo -- sh -c 'cat '\\''/sys/devices/system/cpu/online'\\'''\n", "2016-11-17 17:01:03,868 DEBUG : cat /sys/devices/system/cpu/cpu0/cpufreq/scaling_available_frequencies\n", "2016-11-17 17:01:04,276 DEBUG : sudo -- sh -c 'cat '\\''/sys/devices/system/cpu/online'\\'''\n", "2016-11-17 17:01:05,181 DEBUG : cat /sys/devices/system/cpu/cpu1/cpufreq/scaling_available_frequencies\n", "2016-11-17 17:01:05,490 DEBUG : Platform - Trying to load default EM from /home/bjackman/sources/lisa/libs/utils/platforms/juno.json\n", "2016-11-17 17:01:05,491 DEBUG : Platform - Platform descriptor initialized\n", "{'nrg_model': None, 'clusters': {'big': [1, 2], 'little': [0, 3, 4, 5]}, 'cpus_count': 6, 'freqs': {'big': [450000, 625000, 800000, 950000, 1100000], 'little': [450000, 575000, 700000, 775000, 850000]}, 'topology': [[0, 3, 4, 5], [1, 2]]}\n", "2016-11-17 17:01:05,509 DEBUG : /usr/bin/scp -r /home/bjackman/sources/lisa/libs/devlib/devlib/bin/arm64/trace-cmd brendan@192.168.0.1:/home/brendan/devlib-target/bin/trace-cmd\n", "2016-11-17 17:01:05,949 DEBUG : chmod a+x /home/brendan/devlib-target/bin/trace-cmd\n", "2016-11-17 17:01:06,357 DEBUG : sudo -- sh -c 'cat /sys/kernel/debug/tracing/available_events'\n", "2016-11-17 17:01:07,172 INFO : FTrace - Enabled tracepoints:\n", "2016-11-17 17:01:07,173 INFO : FTrace - sched_switch\n", "2016-11-17 17:01:07,174 INFO : FTrace - sched_wakeup\n", "2016-11-17 17:01:07,175 INFO : FTrace - sched_wakeup_new\n", "2016-11-17 17:01:07,176 INFO : FTrace - cpu_frequency\n", "2016-11-17 17:01:07,177 DEBUG : No RT-App workloads, skipping calibration\n", "2016-11-17 17:01:07,178 WARNING : TestEnv - Wipe previous contents of the results folder:\n", "2016-11-17 17:01:07,179 WARNING : TestEnv - /home/bjackman/sources/lisa/results/ExecutorExample\n", "2016-11-17 17:01:07,181 DEBUG : EnergyMeter - using default energy meter for [juno]\n", "2016-11-17 17:01:07,182 INFO : HWMon - Scanning for HWMON channels, may take some time...\n", "2016-11-17 17:01:07,184 DEBUG : Discovering available HWMON sensors...\n", "2016-11-17 17:01:07,185 DEBUG : \tAdding sensor scpi_sensors/energy1\n", "2016-11-17 17:01:07,186 DEBUG : \tAdding sensor scpi_sensors/energy2\n", "2016-11-17 17:01:07,187 DEBUG : \tAdding sensor scpi_sensors/energy3\n", "2016-11-17 17:01:07,188 DEBUG : \tAdding sensor scpi_sensors/energy4\n", "2016-11-17 17:01:07,189 DEBUG : \tAdding sensor scpi_sensors/curr1\n", "2016-11-17 17:01:07,191 DEBUG : \tAdding sensor scpi_sensors/curr2\n", "2016-11-17 17:01:07,192 DEBUG : \tAdding sensor scpi_sensors/curr3\n", "2016-11-17 17:01:07,193 DEBUG : \tAdding sensor scpi_sensors/curr4\n", "2016-11-17 17:01:07,194 DEBUG : \tAdding sensor scpi_sensors/temp1\n", "2016-11-17 17:01:07,195 DEBUG : \tAdding sensor scpi_sensors/temp2\n", "2016-11-17 17:01:07,196 DEBUG : \tAdding sensor scpi_sensors/power1\n", "2016-11-17 17:01:07,198 DEBUG : \tAdding sensor scpi_sensors/power2\n", "2016-11-17 17:01:07,199 DEBUG : \tAdding sensor scpi_sensors/power3\n", "2016-11-17 17:01:07,200 DEBUG : \tAdding sensor scpi_sensors/power4\n", "2016-11-17 17:01:07,201 DEBUG : \tAdding sensor scpi_sensors/in0\n", "2016-11-17 17:01:07,202 DEBUG : \tAdding sensor scpi_sensors/in1\n", "2016-11-17 17:01:07,203 DEBUG : \tAdding sensor scpi_sensors/in2\n", "2016-11-17 17:01:07,204 DEBUG : \tAdding sensor scpi_sensors/in3\n", "2016-11-17 17:01:07,205 DEBUG : \tAdding sensor scpi_sensors/in4\n", "2016-11-17 17:01:07,206 DEBUG : \tAdding sensor scpi_sensors/in5\n", "2016-11-17 17:01:07,206 DEBUG : \tAdding sensor scpi_sensors/in6\n", "2016-11-17 17:01:07,207 DEBUG : HWMon - Enabling channels {'kinds': ['energy'], 'sites': ['BOARDLITTLE', 'BOARDBIG']}\n", "2016-11-17 17:01:07,208 INFO : HWMon - Channels selected for energy sampling:\n", "2016-11-17 17:01:07,209 INFO : HWMon - BOARDBIG_energy\n", "2016-11-17 17:01:07,210 INFO : HWMon - BOARDLITTLE_energy\n", "2016-11-17 17:01:07,211 DEBUG : EnergyMeter - Results dir: /home/bjackman/sources/lisa/results/ExecutorExample\n", "2016-11-17 17:01:07,212 INFO : TestEnv - Set results folder to:\n", "2016-11-17 17:01:07,213 INFO : TestEnv - /home/bjackman/sources/lisa/results/ExecutorExample\n", "2016-11-17 17:01:07,214 INFO : TestEnv - Experiment results available also in:\n", "2016-11-17 17:01:07,215 INFO : TestEnv - /home/bjackman/sources/lisa/results_latest\n" ] } ], "source": [ "from env import TestEnv\n", "\n", "# Setup a test environment with target configuration\n", "env = TestEnv({\n", " \n", " # Target platform and board\n", " \"platform\" : 'linux',\n", " \"board\" : 'juno',\n", " \n", " # Target board IP/MAC address\n", " \"host\" : '192.168.0.1',\n", " \n", " # Login credentials\n", " \"username\" : 'root',\n", " \"password\" : 'test0000',\n", "\n", " # Folder where all the results will be collected\n", " \"results_dir\" : \"ExecutorExample\",\n", " \n", " # FTrace events to collect for all the tests configuration which have\n", " # the \"ftrace\" flag enabled\n", " \"ftrace\" : {\n", " \"events\" : [\n", " \"sched_switch\",\n", " \"sched_wakeup\",\n", " \"sched_wakeup_new\",\n", " \"cpu_frequency\",\n", " ],\n", " \"buffsize\" : 80 * 1024,\n", " },\n", " \n", " # Tools required by the experiments\n", " \"tools\" : [ 'trace-cmd', 'perf' ],\n", " \n", " # Modules required by these experiments\n", " \"modules\" : [ 'bl', 'cpufreq' ],\n", "})" ] }, { "cell_type": "markdown", "metadata": {}, "source": [ "# Tests Configuration" ] }, { "cell_type": "code", "execution_count": 4, "metadata": { "collapsed": false, "scrolled": false }, "outputs": [], "source": [ "my_tests_conf = {\n", "\n", " # Platform configurations to test\n", " \"confs\" : [\n", " {\n", " \"tag\" : \"base\",\n", " \"flags\" : \"ftrace\", # Enable FTrace events\n", " \"sched_features\" : \"NO_ENERGY_AWARE\", # Disable EAS\n", " \"cpufreq\" : { # Use PERFORMANCE CpuFreq\n", " \"governor\" : \"performance\",\n", " },\n", " },\n", " {\n", " \"tag\" : \"eas\",\n", " \"flags\" : \"ftrace\", # Enable FTrace events\n", " \"sched_features\" : \"ENERGY_AWARE\", # Enable EAS\n", " \"cpufreq\" : { # Use PERFORMANCE CpuFreq\n", " \"governor\" : \"performance\",\n", " },\n", " },\n", " ],\n", " \n", " # Workloads to run (on each platform configuration)\n", " \"wloads\" : {\n", " # Run hackbench with 1 group using pipes\n", " \"perf\" : {\n", " \"type\" : \"perf_bench\",\n", " \"conf\" : {\n", " \"class\" : \"messaging\",\n", " \"params\" : {\n", " \"group\" : 1,\n", " \"loop\" : 10,\n", " \"pipe\" : True,\n", " \"thread\": True,\n", " }\n", " }\n", " },\n", " # Run a 20% duty-cycle periodic task\n", " \"rta\" : {\n", " \"type\" : \"rt-app\",\n", " \"loadref\" : \"big\",\n", " \"conf\" : {\n", " \"class\" : \"profile\",\n", " \"params\" : {\n", " \"p20\" : {\n", " \"kind\" : \"Periodic\",\n", " \"params\" : {\n", " \"duty_cycle_pct\" : 20,\n", " },\n", " },\n", " },\n", " },\n", " },\n", " },\n", " \n", " # Number of iterations for each workload\n", " \"iterations\" : 1,\n", "}" ] }, { "cell_type": "markdown", "metadata": {}, "source": [ "# Tests execution" ] }, { "cell_type": "code", "execution_count": 5, "metadata": { "collapsed": false }, "outputs": [ { "name": "stderr", "output_type": "stream", "text": [ "05:01:07 INFO : Target - Loading custom (inline) test configuration\n", "05:01:07 INFO : \n", "05:01:07 INFO : ################################################################################\n", "05:01:07 INFO : Executor - Experiments configuration\n", "05:01:07 INFO : ################################################################################\n", "05:01:07 INFO : Executor - Configured to run:\n", "05:01:07 INFO : Executor - 2 target configurations:\n", "05:01:07 INFO : Executor - base, eas\n", "05:01:07 INFO : Executor - 2 workloads (1 iterations each)\n", "05:01:07 INFO : Executor - rta, perf\n", "05:01:07 INFO : Executor - Total: 4 experiments\n", "05:01:07 INFO : Executor - Results will be collected under:\n", "05:01:07 INFO : Executor - /home/bjackman/sources/lisa/results/ExecutorExample\n" ] } ], "source": [ "from executor import Executor\n", "\n", "executor = Executor(env, my_tests_conf)" ] }, { "cell_type": "code", "execution_count": 6, "metadata": { "collapsed": false, "scrolled": true }, "outputs": [ { "name": "stderr", "output_type": "stream", "text": [ "05:01:07 INFO : \n", "05:01:07 INFO : ################################################################################\n", "05:01:07 INFO : Executor - Experiments execution\n", "05:01:07 INFO : ################################################################################\n", "05:01:07 INFO : \n", "05:01:07 INFO : ================================================================================\n", "05:01:07 INFO : TargetConfig - configuring target for [base] experiments\n", "2016-11-17 17:01:07,401 DEBUG : sudo -- sh -c '[ -d /home/brendan/devlib-target/run_dir ] || mkdir /home/brendan/devlib-target/run_dir'\n", "2016-11-17 17:01:08,306 DEBUG : sudo -- sh -c 'grep schedtest /proc/mounts || mount -t tmpfs -o size=1024m schedtest /home/brendan/devlib-target/run_dir'\n", "05:01:09 INFO : SchedFeatures - Set scheduler feature: NO_ENERGY_AWARE\n", "2016-11-17 17:01:09,212 DEBUG : sudo -- sh -c 'echo NO_ENERGY_AWARE > /sys/kernel/debug/sched_features'\n", "05:01:10 INFO : CPUFreq - Configuring all CPUs to use [performance] governor\n", "2016-11-17 17:01:10,118 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_set_all_governors performance'\n", "05:01:10 INFO : WlGen - Setup new workload rta\n", "05:01:10 INFO : RTApp - Workload duration defined by longest task\n", "05:01:10 INFO : RTApp - Default policy: SCHED_OTHER\n", "05:01:10 INFO : RTApp - ------------------------\n", "05:01:10 INFO : RTApp - task [task_p200], sched: using default policy\n", "05:01:10 INFO : RTApp - | calibration CPU: 1\n", "05:01:10 INFO : RTApp - | loops count: 1\n", "05:01:10 INFO : RTApp - + phase_000001: duration 1.000000 [s] (10 loops)\n", "05:01:10 INFO : RTApp - | period 100000 [us], duty_cycle 20 %\n", "05:01:10 INFO : RTApp - | run_time 20000 [us], sleep_time 80000 [us]\n", "2016-11-17 17:01:10,934 DEBUG : /usr/bin/scp -r rta_00.json brendan@192.168.0.1:/home/brendan/devlib-target/run_dir\n", "2016-11-17 17:01:11,184 DEBUG : zcat /proc/config.gz\n", "2016-11-17 17:01:11,706 DEBUG : /home/brendan/devlib-target/bin/busybox uname -a\n", "05:01:12 INFO : WlGen - Setup new workload perf\n", "2016-11-17 17:01:12,149 DEBUG : /home/brendan/devlib-target/bin/busybox uname -a\n", "05:01:12 INFO : ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n", "05:01:12 INFO : Executor - Experiment 0/4, [base:rta] 1/1\n", "05:01:12 WARNING : Executor - FTrace events collection enabled\n", "2016-11-17 17:01:12,569 DEBUG : sudo -- sh -c 'echo 81920 > '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:01:13,272 DEBUG : sudo -- sh -c 'cat '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:01:14,178 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd reset'\n", "2016-11-17 17:01:15,707 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd start -e sched_switch -e sched_wakeup -e sched_wakeup_new -e cpu_frequency'\n", "2016-11-17 17:01:17,247 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_START > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:01:17,850 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:01:17,951 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:01:18,857 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:01:19,762 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "05:01:20 INFO : WlGen - Workload execution START:\n", "05:01:20 INFO : WlGen - /home/brendan/devlib-target/bin/rt-app /home/brendan/devlib-target/run_dir/rta_00.json 2>&1\n", "2016-11-17 17:01:20,671 DEBUG : /home/brendan/devlib-target/bin/rt-app /home/brendan/devlib-target/run_dir/rta_00.json 2>&1\n", "2016-11-17 17:01:22,087 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:'/home/brendan/devlib-target/run_dir/*task_p200*.log' /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:base:rta/1\n", "2016-11-17 17:01:22,240 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/run_dir/rta_00.json /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:base:rta/1\n", "2016-11-17 17:01:22,493 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:01:23,400 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "2016-11-17 17:01:24,205 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:01:24,307 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:01:25,213 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_STOP > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:01:25,915 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd stop'\n", "2016-11-17 17:01:26,821 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd extract -o /home/brendan/devlib-target/trace.dat'\n", "2016-11-17 17:01:28,593 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/trace.dat /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:base:rta/1/trace.dat\n", "05:01:35 INFO : Executor - Collected FTrace binary trace:\n", "05:01:35 INFO : Executor - /rtapp:base:rta/1/trace.dat\n", "05:01:35 INFO : Executor - Collected FTrace function profiling:\n", "05:01:35 INFO : Executor - /rtapp:base:rta/1/trace_stat.json\n", "05:01:35 INFO : --------------------------------------------------------------------------------\n", "05:01:35 INFO : ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n", "05:01:35 INFO : Executor - Experiment 1/4, [base:perf] 1/1\n", "05:01:35 WARNING : Executor - FTrace events collection enabled\n", "2016-11-17 17:01:35,737 DEBUG : sudo -- sh -c 'echo 81920 > '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:01:36,440 DEBUG : sudo -- sh -c 'cat '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:01:37,346 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd reset'\n", "2016-11-17 17:01:38,888 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd start -e sched_switch -e sched_wakeup -e sched_wakeup_new -e cpu_frequency'\n", "2016-11-17 17:01:40,434 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_START > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:01:41,037 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:01:41,139 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:01:42,045 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:01:42,951 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "05:01:43 INFO : WlGen - Workload execution START:\n", "05:01:43 INFO : WlGen - /home/brendan/devlib-target/bin/perf bench sched messaging --pipe --thread --group 1 --loop 10\n", "2016-11-17 17:01:43,859 DEBUG : /home/brendan/devlib-target/bin/perf bench sched messaging --pipe --thread --group 1 --loop 10\n", "05:01:44 INFO : PerfBench - Completion time: 0.012000, Performance 83.333333\n", "2016-11-17 17:01:44,284 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:01:45,190 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "2016-11-17 17:01:45,995 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:01:46,097 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:01:47,003 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_STOP > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:01:49,258 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd stop'\n", "2016-11-17 17:01:50,164 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd extract -o /home/brendan/devlib-target/trace.dat'\n", "2016-11-17 17:01:51,947 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/trace.dat /home/bjackman/sources/lisa/results/ExecutorExample/perf_bench_messaging:base:perf/1/trace.dat\n", "05:01:52 INFO : Executor - Collected FTrace binary trace:\n", "05:01:52 INFO : Executor - /perf_bench_messaging:base:perf/1/trace.dat\n", "05:01:52 INFO : Executor - Collected FTrace function profiling:\n", "05:01:52 INFO : Executor - /perf_bench_messaging:base:perf/1/trace_stat.json\n", "05:01:52 INFO : --------------------------------------------------------------------------------\n", "05:01:52 INFO : \n", "05:01:52 INFO : ================================================================================\n", "05:01:52 INFO : TargetConfig - configuring target for [eas] experiments\n", "2016-11-17 17:01:52,740 DEBUG : sudo -- sh -c '[ -d /home/brendan/devlib-target/run_dir ] || mkdir /home/brendan/devlib-target/run_dir'\n", "2016-11-17 17:01:53,646 DEBUG : sudo -- sh -c 'grep schedtest /proc/mounts || mount -t tmpfs -o size=1024m schedtest /home/brendan/devlib-target/run_dir'\n", "05:01:54 INFO : SchedFeatures - Set scheduler feature: ENERGY_AWARE\n", "2016-11-17 17:01:54,552 DEBUG : sudo -- sh -c 'echo ENERGY_AWARE > /sys/kernel/debug/sched_features'\n", "05:01:55 INFO : CPUFreq - Configuring all CPUs to use [performance] governor\n", "2016-11-17 17:01:55,460 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_set_all_governors performance'\n", "05:01:56 INFO : WlGen - Setup new workload rta\n", "05:01:56 INFO : RTApp - Workload duration defined by longest task\n", "05:01:56 INFO : RTApp - Default policy: SCHED_OTHER\n", "05:01:56 INFO : RTApp - ------------------------\n", "05:01:56 INFO : RTApp - task [task_p200], sched: using default policy\n", "05:01:56 INFO : RTApp - | calibration CPU: 1\n", "05:01:56 INFO : RTApp - | loops count: 1\n", "05:01:56 INFO : RTApp - + phase_000001: duration 1.000000 [s] (10 loops)\n", "05:01:56 INFO : RTApp - | period 100000 [us], duty_cycle 20 %\n", "05:01:56 INFO : RTApp - | run_time 20000 [us], sleep_time 80000 [us]\n", "2016-11-17 17:01:56,276 DEBUG : /usr/bin/scp -r rta_00.json brendan@192.168.0.1:/home/brendan/devlib-target/run_dir\n", "2016-11-17 17:01:56,579 DEBUG : /home/brendan/devlib-target/bin/busybox uname -a\n", "05:01:56 INFO : WlGen - Setup new workload perf\n", "2016-11-17 17:01:57,022 DEBUG : /home/brendan/devlib-target/bin/busybox uname -a\n", "05:01:57 INFO : ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n", "05:01:57 INFO : Executor - Experiment 0/4, [base:rta] 1/1\n", "05:01:57 WARNING : Executor - FTrace events collection enabled\n", "2016-11-17 17:01:57,442 DEBUG : sudo -- sh -c 'echo 81920 > '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:01:58,146 DEBUG : sudo -- sh -c 'cat '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:01:59,052 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd reset'\n", "2016-11-17 17:02:00,818 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd start -e sched_switch -e sched_wakeup -e sched_wakeup_new -e cpu_frequency'\n", "2016-11-17 17:02:02,450 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_START > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:03,052 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:03,154 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:04,061 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:04,967 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "05:02:05 INFO : WlGen - Workload execution START:\n", "05:02:05 INFO : WlGen - /home/brendan/devlib-target/bin/rt-app /home/brendan/devlib-target/run_dir/rta_00.json 2>&1\n", "2016-11-17 17:02:05,875 DEBUG : /home/brendan/devlib-target/bin/rt-app /home/brendan/devlib-target/run_dir/rta_00.json 2>&1\n", "2016-11-17 17:02:07,294 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:'/home/brendan/devlib-target/run_dir/*task_p200*.log' /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:base:rta/1\n", "2016-11-17 17:02:07,464 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/run_dir/rta_00.json /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:base:rta/1\n", "2016-11-17 17:02:07,734 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:08,640 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "2016-11-17 17:02:09,446 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:09,548 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:10,453 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_STOP > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:11,156 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd stop'\n", "2016-11-17 17:02:12,062 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd extract -o /home/brendan/devlib-target/trace.dat'\n", "2016-11-17 17:02:14,012 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/trace.dat /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:base:rta/1/trace.dat\n", "05:02:14 INFO : Executor - Collected FTrace binary trace:\n", "05:02:14 INFO : Executor - /rtapp:base:rta/1/trace.dat\n", "05:02:14 INFO : Executor - Collected FTrace function profiling:\n", "05:02:14 INFO : Executor - /rtapp:base:rta/1/trace_stat.json\n", "05:02:14 INFO : --------------------------------------------------------------------------------\n", "05:02:14 INFO : ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n", "05:02:14 INFO : Executor - Experiment 1/4, [base:perf] 1/1\n", "05:02:14 WARNING : Executor - FTrace events collection enabled\n", "2016-11-17 17:02:14,771 DEBUG : sudo -- sh -c 'echo 81920 > '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:02:15,473 DEBUG : sudo -- sh -c 'cat '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:02:16,379 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd reset'\n", "2016-11-17 17:02:18,021 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd start -e sched_switch -e sched_wakeup -e sched_wakeup_new -e cpu_frequency'\n", "2016-11-17 17:02:19,703 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_START > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:20,305 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:20,407 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:21,313 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:22,219 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "05:02:23 INFO : WlGen - Workload execution START:\n", "05:02:23 INFO : WlGen - /home/brendan/devlib-target/bin/perf bench sched messaging --pipe --thread --group 1 --loop 10\n", "2016-11-17 17:02:23,127 DEBUG : /home/brendan/devlib-target/bin/perf bench sched messaging --pipe --thread --group 1 --loop 10\n", "05:02:23 INFO : PerfBench - Completion time: 0.080000, Performance 12.500000\n", "2016-11-17 17:02:23,628 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:24,534 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "2016-11-17 17:02:25,340 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:25,442 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:26,347 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_STOP > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:27,050 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd stop'\n", "2016-11-17 17:02:27,956 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd extract -o /home/brendan/devlib-target/trace.dat'\n", "2016-11-17 17:02:30,090 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/trace.dat /home/bjackman/sources/lisa/results/ExecutorExample/perf_bench_messaging:base:perf/1/trace.dat\n", "05:02:30 INFO : Executor - Collected FTrace binary trace:\n", "05:02:30 INFO : Executor - /perf_bench_messaging:base:perf/1/trace.dat\n", "05:02:30 INFO : Executor - Collected FTrace function profiling:\n", "05:02:30 INFO : Executor - /perf_bench_messaging:base:perf/1/trace_stat.json\n", "05:02:30 INFO : --------------------------------------------------------------------------------\n", "05:02:30 INFO : ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n", "05:02:30 INFO : Executor - Experiment 2/4, [eas:rta] 1/1\n", "05:02:30 WARNING : Executor - FTrace events collection enabled\n", "2016-11-17 17:02:30,869 DEBUG : sudo -- sh -c 'echo 81920 > '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:02:31,572 DEBUG : sudo -- sh -c 'cat '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:02:32,478 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd reset'\n", "2016-11-17 17:02:34,271 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd start -e sched_switch -e sched_wakeup -e sched_wakeup_new -e cpu_frequency'\n", "2016-11-17 17:02:35,963 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_START > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:36,565 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:36,667 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:37,573 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:38,479 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "05:02:39 INFO : WlGen - Workload execution START:\n", "05:02:39 INFO : WlGen - /home/brendan/devlib-target/bin/rt-app /home/brendan/devlib-target/run_dir/rta_00.json 2>&1\n", "2016-11-17 17:02:39,387 DEBUG : /home/brendan/devlib-target/bin/rt-app /home/brendan/devlib-target/run_dir/rta_00.json 2>&1\n", "2016-11-17 17:02:40,807 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:'/home/brendan/devlib-target/run_dir/*task_p200*.log' /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:eas:rta/1\n", "2016-11-17 17:02:40,980 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/run_dir/rta_00.json /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:eas:rta/1\n", "2016-11-17 17:02:41,231 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:42,137 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "2016-11-17 17:02:42,943 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:43,045 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:43,950 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_STOP > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:44,653 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd stop'\n", "2016-11-17 17:02:45,559 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd extract -o /home/brendan/devlib-target/trace.dat'\n", "2016-11-17 17:02:47,519 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/trace.dat /home/bjackman/sources/lisa/results/ExecutorExample/rtapp:eas:rta/1/trace.dat\n", "05:02:48 INFO : Executor - Collected FTrace binary trace:\n", "05:02:48 INFO : Executor - /rtapp:eas:rta/1/trace.dat\n", "05:02:48 INFO : Executor - Collected FTrace function profiling:\n", "05:02:48 INFO : Executor - /rtapp:eas:rta/1/trace_stat.json\n", "05:02:48 INFO : --------------------------------------------------------------------------------\n", "05:02:48 INFO : ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n", "05:02:48 INFO : Executor - Experiment 3/4, [eas:perf] 1/1\n", "05:02:48 WARNING : Executor - FTrace events collection enabled\n", "2016-11-17 17:02:48,276 DEBUG : sudo -- sh -c 'echo 81920 > '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:02:48,979 DEBUG : sudo -- sh -c 'cat '\\''/sys/kernel/debug/tracing/buffer_size_kb'\\'''\n", "2016-11-17 17:02:49,885 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd reset'\n", "2016-11-17 17:02:51,485 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd start -e sched_switch -e sched_wakeup -e sched_wakeup_new -e cpu_frequency'\n", "2016-11-17 17:02:53,160 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_START > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:02:53,763 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:53,865 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:54,771 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:55,676 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "05:02:56 INFO : WlGen - Workload execution START:\n", "05:02:56 INFO : WlGen - /home/brendan/devlib-target/bin/perf bench sched messaging --pipe --thread --group 1 --loop 10\n", "2016-11-17 17:02:56,585 DEBUG : /home/brendan/devlib-target/bin/perf bench sched messaging --pipe --thread --group 1 --loop 10\n", "05:02:56 INFO : PerfBench - Completion time: 0.083000, Performance 12.048193\n", "2016-11-17 17:02:57,088 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy2_input'\\'''\n", "2016-11-17 17:02:57,994 DEBUG : sudo -- sh -c 'cat '\\''/sys/class/hwmon/hwmon0/energy3_input'\\'''\n", "2016-11-17 17:02:58,799 DEBUG : Trace CPUFreq frequencies\n", "2016-11-17 17:02:58,901 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/shutils cpufreq_trace_all_frequencies'\n", "2016-11-17 17:02:59,807 DEBUG : sudo -- sh -c 'echo TRACE_MARKER_STOP > '\\''/sys/kernel/debug/tracing/trace_marker'\\'''\n", "2016-11-17 17:03:00,510 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd stop'\n", "2016-11-17 17:03:01,415 DEBUG : sudo -- sh -c '/home/brendan/devlib-target/bin/trace-cmd extract -o /home/brendan/devlib-target/trace.dat'\n", "2016-11-17 17:03:03,385 DEBUG : /usr/bin/scp -r brendan@192.168.0.1:/home/brendan/devlib-target/trace.dat /home/bjackman/sources/lisa/results/ExecutorExample/perf_bench_messaging:eas:perf/1/trace.dat\n", "05:03:04 INFO : Executor - Collected FTrace binary trace:\n", "05:03:04 INFO : Executor - /perf_bench_messaging:eas:perf/1/trace.dat\n", "05:03:04 INFO : Executor - Collected FTrace function profiling:\n", "05:03:04 INFO : Executor - /perf_bench_messaging:eas:perf/1/trace_stat.json\n", "05:03:04 INFO : --------------------------------------------------------------------------------\n", "05:03:04 INFO : \n", "05:03:04 INFO : ################################################################################\n", "05:03:04 INFO : Executor - Experiments execution completed\n", "05:03:04 INFO : ################################################################################\n", "05:03:04 INFO : Executor - Results available in:\n", "05:03:04 INFO : Executor - /home/bjackman/sources/lisa/results/ExecutorExample\n" ] } ], "source": [ "executor.run()" ] }, { "cell_type": "code", "execution_count": 7, "metadata": { "collapsed": false }, "outputs": [ { "name": "stdout", "output_type": "stream", "text": [ "\u001b[01;34m/home/bjackman/sources/lisa/results/ExecutorExample\u001b[00m\r\n", "├── \u001b[01;34mperf_bench_messaging:base:perf\u001b[00m\r\n", "│   ├── \u001b[01;34m1\u001b[00m\r\n", "│   │   ├── energy.json\r\n", "│   │   ├── output.log\r\n", "│   │   ├── performance.json\r\n", "│   │   └── trace.dat\r\n", "│   ├── kernel.config\r\n", "│   ├── kernel.version\r\n", "│   └── platform.json\r\n", "├── \u001b[01;34mperf_bench_messaging:eas:perf\u001b[00m\r\n", "│   ├── \u001b[01;34m1\u001b[00m\r\n", "│   │   ├── energy.json\r\n", "│   │   ├── output.log\r\n", "│   │   ├── performance.json\r\n", "│   │   └── trace.dat\r\n", "│   ├── kernel.config\r\n", "│   ├── kernel.version\r\n", "│   └── platform.json\r\n", "├── \u001b[01;34mrtapp:base:rta\u001b[00m\r\n", "│   ├── \u001b[01;34m1\u001b[00m\r\n", "│   │   ├── energy.json\r\n", "│   │   ├── output.log\r\n", "│   │   ├── rta_00.json\r\n", "│   │   ├── rt-app-task_p200-0.log\r\n", "│   │   └── trace.dat\r\n", "│   ├── kernel.config\r\n", "│   ├── kernel.version\r\n", "│   └── platform.json\r\n", "└── \u001b[01;34mrtapp:eas:rta\u001b[00m\r\n", " ├── \u001b[01;34m1\u001b[00m\r\n", " │   ├── energy.json\r\n", " │   ├── output.log\r\n", " │   ├── rta_00.json\r\n", " │   ├── rt-app-task_p200-0.log\r\n", " │   └── trace.dat\r\n", " ├── kernel.config\r\n", " ├── kernel.version\r\n", " └── platform.json\r\n", "\r\n", "8 directories, 30 files\r\n" ] } ], "source": [ "!tree {executor.te.res_dir}" ] } ], "metadata": { "kernelspec": { "display_name": "Python 2", "language": "python", "name": "python2" }, "language_info": { "codemirror_mode": { "name": "ipython", "version": 2 }, "file_extension": ".py", "mimetype": "text/x-python", "name": "python", "nbconvert_exporter": "python", "pygments_lexer": "ipython2", "version": "2.7.6" } }, "nbformat": 4, "nbformat_minor": 0 }