blob: 239f609c4a0f2cfd44b7a1be5f5ff7f8169f7ad5 [file] [log] [blame]
{
"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': 'juno2', '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/juno2.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 [juno2]\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\" : 'juno2',\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 - <res_dir>/rtapp:base:rta/1/trace.dat\n",
"05:01:35 INFO : Executor - Collected FTrace function profiling:\n",
"05:01:35 INFO : Executor - <res_dir>/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 - <res_dir>/perf_bench_messaging:base:perf/1/trace.dat\n",
"05:01:52 INFO : Executor - Collected FTrace function profiling:\n",
"05:01:52 INFO : Executor - <res_dir>/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 - <res_dir>/rtapp:base:rta/1/trace.dat\n",
"05:02:14 INFO : Executor - Collected FTrace function profiling:\n",
"05:02:14 INFO : Executor - <res_dir>/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 - <res_dir>/perf_bench_messaging:base:perf/1/trace.dat\n",
"05:02:30 INFO : Executor - Collected FTrace function profiling:\n",
"05:02:30 INFO : Executor - <res_dir>/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 - <res_dir>/rtapp:eas:rta/1/trace.dat\n",
"05:02:48 INFO : Executor - Collected FTrace function profiling:\n",
"05:02:48 INFO : Executor - <res_dir>/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 - <res_dir>/perf_bench_messaging:eas:perf/1/trace.dat\n",
"05:03:04 INFO : Executor - Collected FTrace function profiling:\n",
"05:03:04 INFO : Executor - <res_dir>/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
}