From c72e3e48420864af6c688ae6b9bc6f159c916598 Mon Sep 17 00:00:00 2001 From: Stefan Hajnoczi Date: Wed, 16 Dec 2020 16:09:21 +0000 Subject: [PATCH 01/11] trace: fix simpletrace doc mismerge The simpletrace documentation section was accidentally split when the ftrace section was introduced. Move the simpletrace-specific documentation back into the simpletrace section. Fixes: e64dd5efb2c6d522a3bc9d096cd49a4e53f0ae10 ("trace: document ftrace backend") Reviewed-by: Peter Maydell Signed-off-by: Stefan Hajnoczi Message-id: 20201216160923.722894-2-stefanha@redhat.com Signed-off-by: Stefan Hajnoczi --- docs/devel/tracing.txt | 34 +++++++++++++++++----------------- 1 file changed, 17 insertions(+), 17 deletions(-) diff --git a/docs/devel/tracing.txt b/docs/devel/tracing.txt index dba43fc7a409..313b8ea4e969 100644 --- a/docs/devel/tracing.txt +++ b/docs/devel/tracing.txt @@ -218,6 +218,23 @@ source tree. It may not be as powerful as platform-specific or third-party trace backends but it is portable. This is the recommended trace backend unless you have specific needs for more advanced backends. +==== Monitor commands ==== + +* trace-file on|off|flush|set + Enable/disable/flush the trace file or set the trace file name. + +==== Analyzing trace files ==== + +The "simple" backend produces binary trace files that can be formatted with the +simpletrace.py script. The script takes the "trace-events-all" file and the +binary trace: + + ./scripts/simpletrace.py trace-events-all trace-12345 + +You must ensure that the same "trace-events-all" file was used to build QEMU, +otherwise trace event declarations may have changed and output will not be +consistent. + === Ftrace === The "ftrace" backend writes trace data to ftrace marker. This effectively @@ -246,23 +263,6 @@ NOTE: syslog may squash duplicate consecutive trace events and apply rate Restriction: "syslog" backend is restricted to POSIX compliant OS. -==== Monitor commands ==== - -* trace-file on|off|flush|set - Enable/disable/flush the trace file or set the trace file name. - -==== Analyzing trace files ==== - -The "simple" backend produces binary trace files that can be formatted with the -simpletrace.py script. The script takes the "trace-events-all" file and the -binary trace: - - ./scripts/simpletrace.py trace-events-all trace-12345 - -You must ensure that the same "trace-events-all" file was used to build QEMU, -otherwise trace event declarations may have changed and output will not be -consistent. - === LTTng Userspace Tracer === The "ust" backend uses the LTTng Userspace Tracer library. There are no From e50caf4a5c0cb8d478e0c80b11d5a8a9306a89fd Mon Sep 17 00:00:00 2001 From: Stefan Hajnoczi Date: Wed, 16 Dec 2020 16:09:22 +0000 Subject: [PATCH 02/11] tracing: convert documentation to rST This is a simple rST conversion of the documentation. Reviewed-by: Peter Maydell Signed-off-by: Stefan Hajnoczi Message-id: 20201216160923.722894-3-stefanha@redhat.com Signed-off-by: Stefan Hajnoczi --- docs/devel/index.rst | 1 + docs/devel/{tracing.txt => tracing.rst} | 134 ++++++++++++++---------- 2 files changed, 81 insertions(+), 54 deletions(-) rename docs/devel/{tracing.txt => tracing.rst} (89%) diff --git a/docs/devel/index.rst b/docs/devel/index.rst index ea0e1e17ae6c..98a7016a9b21 100644 --- a/docs/devel/index.rst +++ b/docs/devel/index.rst @@ -28,6 +28,7 @@ Contents: secure-coding-practices tcg tcg-icount + tracing multi-thread-tcg tcg-plugins bitops diff --git a/docs/devel/tracing.txt b/docs/devel/tracing.rst similarity index 89% rename from docs/devel/tracing.txt rename to docs/devel/tracing.rst index 313b8ea4e969..f7e589f67c3b 100644 --- a/docs/devel/tracing.txt +++ b/docs/devel/tracing.rst @@ -1,32 +1,38 @@ -= Tracing = +======= +Tracing +======= -== Introduction == +Introduction +============ This document describes the tracing infrastructure in QEMU and how to use it for debugging, profiling, and observing execution. -== Quickstart == +Quickstart +========== -1. Build with the 'simple' trace backend: +1. Build with the 'simple' trace backend:: ./configure --enable-trace-backends=simple make -2. Create a file with the events you want to trace: +2. Create a file with the events you want to trace:: - echo memory_region_ops_read >/tmp/events + echo memory_region_ops_read >/tmp/events -3. Run the virtual machine to produce a trace file: +3. Run the virtual machine to produce a trace file:: qemu --trace events=/tmp/events ... # your normal QEMU invocation -4. Pretty-print the binary trace file: +4. Pretty-print the binary trace file:: ./scripts/simpletrace.py trace-events-all trace-* # Override * with QEMU -== Trace events == +Trace events +============ -=== Sub-directory setup === +Sub-directory setup +------------------- Each directory in the source tree can declare a set of static trace events in a local "trace-events" file. All directories which contain "trace-events" @@ -50,7 +56,7 @@ In the sub-directory the following files will be automatically generated - trace-ust.h - UST event probe helper declarations Source files in the sub-directory should #include the local 'trace.h' file, -without any sub-directory path prefix. eg io/channel-buffer.c would do +without any sub-directory path prefix. eg io/channel-buffer.c would do:: #include "trace.h" @@ -63,9 +69,10 @@ The top level directory generates trace files with a filename prefix of "trace/trace-root" instead of just "trace". This is to avoid ambiguity between a trace.h in the current directory, vs the top level directory. -=== Using trace events === +Using trace events +------------------ -Trace events are invoked directly from source code like this: +Trace events are invoked directly from source code like this:: #include "trace.h" /* needed for trace event prototype */ @@ -82,7 +89,8 @@ Trace events are invoked directly from source code like this: return ptr; } -=== Declaring trace events === +Declaring trace events +---------------------- The "tracetool" script produces the trace.h header file which is included by every source file that uses trace events. Since many source files include @@ -116,13 +124,14 @@ Format strings must not end with a newline character. It is the responsibility of backends to adapt line ending for proper logging. Each event declaration will start with the event name, then its arguments, -finally a format string for pretty-printing. For example: +finally a format string for pretty-printing. For example:: qemu_vmalloc(size_t size, void *ptr) "size %zu ptr %p" qemu_vfree(void *ptr) "ptr %p" -=== Hints for adding new trace events === +Hints for adding new trace events +--------------------------------- 1. Trace state changes in the code. Interesting points in the code usually involve a state change like starting, stopping, allocating, freeing. State @@ -141,7 +150,8 @@ finally a format string for pretty-printing. For example: 4. Name trace events after their function. If there are multiple trace events in one function, append a unique distinguisher at the end of the name. -== Generic interface and monitor commands == +Generic interface and monitor commands +====================================== You can programmatically query and control the state of trace events through a backend-agnostic interface provided by the header "trace/control.h". @@ -152,11 +162,11 @@ header "trace/control.h" to see which routines are backend-dependent). The state of events can also be queried and modified through monitor commands: -* info trace-events +* ``info trace-events`` View available trace events and their state. State 1 means enabled, state 0 means disabled. -* trace-event NAME on|off +* ``trace-event NAME on|off`` Enable/disable a given trace event or a group of events (using wildcards). The "--trace events=" command line argument can be used to enable the @@ -170,11 +180,12 @@ to enable an entire family of events but one noisy event needs to be disabled. Wildcard matching is supported in both the monitor command "trace-event" and the events list file. That means you can enable/disable the events having a common prefix in a batch. For example, virtio-blk trace events could be enabled using -the following monitor command: +the following monitor command:: trace-event virtio_blk_* on -== Trace backends == +Trace backends +============== The "tracetool" script automates tedious trace event code generation and also keeps the trace event declarations independent of the trace backend. The trace @@ -182,7 +193,7 @@ events are not tightly coupled to a specific trace backend, such as LTTng or SystemTap. Support for trace backends can be added by extending the "tracetool" script. -The trace backends are chosen at configure time: +The trace backends are chosen at configure time:: ./configure --enable-trace-backends=simple @@ -194,7 +205,8 @@ If no backends are explicitly selected, configure will default to the The following subsections describe the supported trace backends. -=== Nop === +Nop +--- The "nop" backend generates empty trace event functions so that the compiler can optimize out trace events completely. This imposes no performance @@ -203,7 +215,8 @@ penalty. Note that regardless of the selected trace backend, events with the "disable" property will be generated with the "nop" backend. -=== Log === +Log +--- The "log" backend sends trace events directly to standard error. This effectively turns trace events into debug printfs. @@ -211,23 +224,26 @@ effectively turns trace events into debug printfs. This is the simplest backend and can be used together with existing code that uses DPRINTF(). -=== Simpletrace === +Simpletrace +----------- The "simple" backend supports common use cases and comes as part of the QEMU source tree. It may not be as powerful as platform-specific or third-party trace backends but it is portable. This is the recommended trace backend unless you have specific needs for more advanced backends. -==== Monitor commands ==== +Monitor commands +~~~~~~~~~~~~~~~~ -* trace-file on|off|flush|set +* ``trace-file on|off|flush|set `` Enable/disable/flush the trace file or set the trace file name. -==== Analyzing trace files ==== +Analyzing trace files +~~~~~~~~~~~~~~~~~~~~~ The "simple" backend produces binary trace files that can be formatted with the simpletrace.py script. The script takes the "trace-events-all" file and the -binary trace: +binary trace:: ./scripts/simpletrace.py trace-events-all trace-12345 @@ -235,23 +251,25 @@ You must ensure that the same "trace-events-all" file was used to build QEMU, otherwise trace event declarations may have changed and output will not be consistent. -=== Ftrace === +Ftrace +------ The "ftrace" backend writes trace data to ftrace marker. This effectively sends trace events to ftrace ring buffer, and you can compare qemu trace data and kernel(especially kvm.ko when using KVM) trace data. -if you use KVM, enable kvm events in ftrace: +if you use KVM, enable kvm events in ftrace:: # echo 1 > /sys/kernel/debug/tracing/events/kvm/enable -After running qemu by root user, you can get the trace: +After running qemu by root user, you can get the trace:: # cat /sys/kernel/debug/tracing/trace Restriction: "ftrace" backend is restricted to Linux only. -=== Syslog === +Syslog +------ The "syslog" backend sends trace events using the POSIX syslog API. The log is opened specifying the LOG_DAEMON facility and LOG_PID option (so events @@ -263,7 +281,8 @@ NOTE: syslog may squash duplicate consecutive trace events and apply rate Restriction: "syslog" backend is restricted to POSIX compliant OS. -=== LTTng Userspace Tracer === +LTTng Userspace Tracer +---------------------- The "ust" backend uses the LTTng Userspace Tracer library. There are no monitor commands built into QEMU, instead UST utilities should be used to list, @@ -275,43 +294,44 @@ lttng-sessiond daemon for the current user prior to running any instance of QEMU. While running an instrumented QEMU, LTTng should be able to list all available -events: +events:: lttng list -u -Create tracing session: +Create tracing session:: lttng create mysession -Enable events: +Enable events:: lttng enable-event qemu:g_malloc -u Where the events can either be a comma-separated list of events, or "-a" to -enable all tracepoint events. Start and stop tracing as needed: +enable all tracepoint events. Start and stop tracing as needed:: lttng start lttng stop -View the trace: +View the trace:: lttng view -Destroy tracing session: +Destroy tracing session:: lttng destroy -Babeltrace can be used at any later time to view the trace: +Babeltrace can be used at any later time to view the trace:: babeltrace $HOME/lttng-traces/mysession--