aboutsummaryrefslogtreecommitdiffstats
path: root/Documentation
diff options
context:
space:
mode:
authorSteven Rostedt (Red Hat) <rostedt@goodmis.org>2014-12-16 10:30:11 -0500
committerSteven Rostedt <rostedt@goodmis.org>2015-01-05 15:03:59 -0500
commit48f4e10dbf504f830f117d6b5733eb535b7c4086 (patch)
tree23014f81ef2559a058f76bcfa2670db4fa1d28f9 /Documentation
parent95167468538f80a8829c71c814184337106d33b1 (diff)
downloadkernel-shark-48f4e10dbf504f830f117d6b5733eb535b7c4086.tar.gz
trace-cmd: Update the man pages for the --profile options
Update the man pages to trace-cmd record and report to show the --profile option. Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
Diffstat (limited to 'Documentation')
-rw-r--r--Documentation/trace-cmd-record.1.txt46
-rw-r--r--Documentation/trace-cmd-report.1.txt42
2 files changed, 88 insertions, 0 deletions
diff --git a/Documentation/trace-cmd-record.1.txt b/Documentation/trace-cmd-record.1.txt
index 9e63eb46..945a5d1b 100644
--- a/Documentation/trace-cmd-record.1.txt
+++ b/Documentation/trace-cmd-record.1.txt
@@ -258,6 +258,15 @@ OPTIONS
timestamp to gettimeofday which will allow wall time output from the
timestamps reading the created 'trace.dat' file.
+*--profile*::
+ With the *--profile* option, "trace-cmd" will enable tracing that can
+ be used with trace-cmd-report(1) --profile option. If a tracer *-p* is
+ not set, and function graph depth is supported by the kernel, then
+ the function_graph tracer will be enabled with a depth of one (only
+ show where userspace enters into the kernel). It will also enable
+ various tracepoints with stack tracing such that the report can show
+ where tasks have been blocked for the longest time.
+
EXAMPLES
--------
@@ -316,6 +325,43 @@ Here is a nice way to find what interrupts have the highest latency:
------------------------------------------
+An example of the profile:
+------------------------------------------
+ # trace-cmd record --profile sleep 1
+ # trace-cmd report --profile --comm sleep
+task: sleep-21611
+ Event: sched_switch:R (1) Total: 99442 Avg: 99442 Max: 99442 Min:99442
+ <stack> 1 total:99442 min:99442 max:99442 avg=99442
+ => ftrace_raw_event_sched_switch (0xffffffff8105f812)
+ => __schedule (0xffffffff8150810a)
+ => preempt_schedule (0xffffffff8150842e)
+ => ___preempt_schedule (0xffffffff81273354)
+ => cpu_stop_queue_work (0xffffffff810b03c5)
+ => stop_one_cpu (0xffffffff810b063b)
+ => sched_exec (0xffffffff8106136d)
+ => do_execve_common.isra.27 (0xffffffff81148c89)
+ => do_execve (0xffffffff811490b0)
+ => SyS_execve (0xffffffff811492c4)
+ => return_to_handler (0xffffffff8150e3c8)
+ => stub_execve (0xffffffff8150c699)
+ Event: sched_switch:S (1) Total: 1000506680 Avg: 1000506680 Max: 1000506680 Min:1000506680
+ <stack> 1 total:1000506680 min:1000506680 max:1000506680 avg=1000506680
+ => ftrace_raw_event_sched_switch (0xffffffff8105f812)
+ => __schedule (0xffffffff8150810a)
+ => schedule (0xffffffff815084b8)
+ => do_nanosleep (0xffffffff8150b22c)
+ => hrtimer_nanosleep (0xffffffff8108d647)
+ => SyS_nanosleep (0xffffffff8108d72c)
+ => return_to_handler (0xffffffff8150e3c8)
+ => tracesys_phase2 (0xffffffff8150c304)
+ Event: sched_wakeup:21611 (1) Total: 30326 Avg: 30326 Max: 30326 Min:30326
+ <stack> 1 total:30326 min:30326 max:30326 avg=30326
+ => ftrace_raw_event_sched_wakeup_template (0xffffffff8105f653)
+ => ttwu_do_wakeup (0xffffffff810606eb)
+ => ttwu_do_activate.constprop.124 (0xffffffff810607c8)
+ => try_to_wake_up (0xffffffff8106340a)
+------------------------------------------
+
SEE ALSO
--------
trace-cmd(1), trace-cmd-report(1), trace-cmd-start(1), trace-cmd-stop(1),
diff --git a/Documentation/trace-cmd-report.1.txt b/Documentation/trace-cmd-report.1.txt
index f88a7aba..ed594e4c 100644
--- a/Documentation/trace-cmd-report.1.txt
+++ b/Documentation/trace-cmd-report.1.txt
@@ -165,6 +165,12 @@ OPTIONS
handler (by the plugins) to print the event. The normal event will be printed
instead. The 'event-re' is a regular expression as defined by 'regcomp(3)'.
+*--profile*::
+ With the *--profile* option, "trace-cmd report" will process all the events
+ first, and then output a format showing where tasks have spent their time
+ in the kernel, as well as where they are blocked the most, and where wake up
+ latencies are.
+
*-R*::
This will show the events in "raw" format. That is, it will ignore the event's
print formatting and just print the contents of each field.
@@ -345,6 +351,42 @@ That is 'prio' 0 is equivalent to user space real time priority 99, and
priority 98 is equivalent to user space real time priority 1.
Prios less than 100 represent Real Time tasks.
+An example of the profile:
+------------------------------------------
+ # trace-cmd record --profile sleep 1
+ # trace-cmd report --profile --comm sleep
+task: sleep-21611
+ Event: sched_switch:R (1) Total: 99442 Avg: 99442 Max: 99442 Min:99442
+ <stack> 1 total:99442 min:99442 max:99442 avg=99442
+ => ftrace_raw_event_sched_switch (0xffffffff8105f812)
+ => __schedule (0xffffffff8150810a)
+ => preempt_schedule (0xffffffff8150842e)
+ => ___preempt_schedule (0xffffffff81273354)
+ => cpu_stop_queue_work (0xffffffff810b03c5)
+ => stop_one_cpu (0xffffffff810b063b)
+ => sched_exec (0xffffffff8106136d)
+ => do_execve_common.isra.27 (0xffffffff81148c89)
+ => do_execve (0xffffffff811490b0)
+ => SyS_execve (0xffffffff811492c4)
+ => return_to_handler (0xffffffff8150e3c8)
+ => stub_execve (0xffffffff8150c699)
+ Event: sched_switch:S (1) Total: 1000506680 Avg: 1000506680 Max: 1000506680 Min:1000506680
+ <stack> 1 total:1000506680 min:1000506680 max:1000506680 avg=1000506680
+ => ftrace_raw_event_sched_switch (0xffffffff8105f812)
+ => __schedule (0xffffffff8150810a)
+ => schedule (0xffffffff815084b8)
+ => do_nanosleep (0xffffffff8150b22c)
+ => hrtimer_nanosleep (0xffffffff8108d647)
+ => SyS_nanosleep (0xffffffff8108d72c)
+ => return_to_handler (0xffffffff8150e3c8)
+ => tracesys_phase2 (0xffffffff8150c304)
+ Event: sched_wakeup:21611 (1) Total: 30326 Avg: 30326 Max: 30326 Min:30326
+ <stack> 1 total:30326 min:30326 max:30326 avg=30326
+ => ftrace_raw_event_sched_wakeup_template (0xffffffff8105f653)
+ => ttwu_do_wakeup (0xffffffff810606eb)
+ => ttwu_do_activate.constprop.124 (0xffffffff810607c8)
+ => try_to_wake_up (0xffffffff8106340a)
+------------------------------------------
SEE ALSO
--------