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