License cleanup: add SPDX GPL-2.0 license identifier to files with no license
Many source files in the tree are missing licensing information, which
makes it harder for compliance tools to determine the correct license.
By default all files without license information are under the default
license of the kernel, which is GPL version 2.
Update the files which contain no license information with the 'GPL-2.0'
SPDX license identifier. The SPDX identifier is a legally binding
shorthand, which can be used instead of the full boiler plate text.
This patch is based on work done by Thomas Gleixner and Kate Stewart and
Philippe Ombredanne.
How this work was done:
Patches were generated and checked against linux-4.14-rc6 for a subset of
the use cases:
- file had no licensing information it it.
- file was a */uapi/* one with no licensing information in it,
- file was a */uapi/* one with existing licensing information,
Further patches will be generated in subsequent months to fix up cases
where non-standard license headers were used, and references to license
had to be inferred by heuristics based on keywords.
The analysis to determine which SPDX License Identifier to be applied to
a file was done in a spreadsheet of side by side results from of the
output of two independent scanners (ScanCode & Windriver) producing SPDX
tag:value files created by Philippe Ombredanne. Philippe prepared the
base worksheet, and did an initial spot review of a few 1000 files.
The 4.13 kernel was the starting point of the analysis with 60,537 files
assessed. Kate Stewart did a file by file comparison of the scanner
results in the spreadsheet to determine which SPDX license identifier(s)
to be applied to the file. She confirmed any determination that was not
immediately clear with lawyers working with the Linux Foundation.
Criteria used to select files for SPDX license identifier tagging was:
- Files considered eligible had to be source code files.
- Make and config files were included as candidates if they contained >5
lines of source
- File already had some variant of a license header in it (even if <5
lines).
All documentation files were explicitly excluded.
The following heuristics were used to determine which SPDX license
identifiers to apply.
- when both scanners couldn't find any license traces, file was
considered to have no license information in it, and the top level
COPYING file license applied.
For non */uapi/* files that summary was:
SPDX license identifier # files
---------------------------------------------------|-------
GPL-2.0 11139
and resulted in the first patch in this series.
If that file was a */uapi/* path one, it was "GPL-2.0 WITH
Linux-syscall-note" otherwise it was "GPL-2.0". Results of that was:
SPDX license identifier # files
---------------------------------------------------|-------
GPL-2.0 WITH Linux-syscall-note 930
and resulted in the second patch in this series.
- if a file had some form of licensing information in it, and was one
of the */uapi/* ones, it was denoted with the Linux-syscall-note if
any GPL family license was found in the file or had no licensing in
it (per prior point). Results summary:
SPDX license identifier # files
---------------------------------------------------|------
GPL-2.0 WITH Linux-syscall-note 270
GPL-2.0+ WITH Linux-syscall-note 169
((GPL-2.0 WITH Linux-syscall-note) OR BSD-2-Clause) 21
((GPL-2.0 WITH Linux-syscall-note) OR BSD-3-Clause) 17
LGPL-2.1+ WITH Linux-syscall-note 15
GPL-1.0+ WITH Linux-syscall-note 14
((GPL-2.0+ WITH Linux-syscall-note) OR BSD-3-Clause) 5
LGPL-2.0+ WITH Linux-syscall-note 4
LGPL-2.1 WITH Linux-syscall-note 3
((GPL-2.0 WITH Linux-syscall-note) OR MIT) 3
((GPL-2.0 WITH Linux-syscall-note) AND MIT) 1
and that resulted in the third patch in this series.
- when the two scanners agreed on the detected license(s), that became
the concluded license(s).
- when there was disagreement between the two scanners (one detected a
license but the other didn't, or they both detected different
licenses) a manual inspection of the file occurred.
- In most cases a manual inspection of the information in the file
resulted in a clear resolution of the license that should apply (and
which scanner probably needed to revisit its heuristics).
- When it was not immediately clear, the license identifier was
confirmed with lawyers working with the Linux Foundation.
- If there was any question as to the appropriate license identifier,
the file was flagged for further research and to be revisited later
in time.
In total, over 70 hours of logged manual review was done on the
spreadsheet to determine the SPDX license identifiers to apply to the
source files by Kate, Philippe, Thomas and, in some cases, confirmation
by lawyers working with the Linux Foundation.
Kate also obtained a third independent scan of the 4.13 code base from
FOSSology, and compared selected files where the other two scanners
disagreed against that SPDX file, to see if there was new insights. The
Windriver scanner is based on an older version of FOSSology in part, so
they are related.
Thomas did random spot checks in about 500 files from the spreadsheets
for the uapi headers and agreed with SPDX license identifier in the
files he inspected. For the non-uapi files Thomas did random spot checks
in about 15000 files.
In initial set of patches against 4.14-rc6, 3 files were found to have
copy/paste license identifier errors, and have been fixed to reflect the
correct identifier.
Additionally Philippe spent 10 hours this week doing a detailed manual
inspection and review of the 12,461 patched files from the initial patch
version early this week with:
- a full scancode scan run, collecting the matched texts, detected
license ids and scores
- reviewing anything where there was a license detected (about 500+
files) to ensure that the applied SPDX license was correct
- reviewing anything where there was no detection but the patch license
was not GPL-2.0 WITH Linux-syscall-note to ensure that the applied
SPDX license was correct
This produced a worksheet with 20 files needing minor correction. This
worksheet was then exported into 3 different .csv files for the
different types of files to be modified.
These .csv files were then reviewed by Greg. Thomas wrote a script to
parse the csv files and add the proper SPDX tag to the file, in the
format that the file expected. This script was further refined by Greg
based on the output to detect more types of files automatically and to
distinguish between header and source .c files (which need different
comment types.) Finally Greg ran the script using the .csv files to
generate the patches.
Reviewed-by: Kate Stewart <kstewart@linuxfoundation.org>
Reviewed-by: Philippe Ombredanne <pombredanne@nexb.com>
Reviewed-by: Thomas Gleixner <tglx@linutronix.de>
Signed-off-by: Greg Kroah-Hartman <gregkh@linuxfoundation.org>
2017-11-01 08:07:57 -06:00
|
|
|
/* SPDX-License-Identifier: GPL-2.0 */
|
2009-06-17 06:22:14 -06:00
|
|
|
#if !defined(_TRACE_KVM_H) || defined(TRACE_HEADER_MULTI_READ)
|
|
|
|
|
#define _TRACE_KVM_H
|
|
|
|
|
|
|
|
|
|
#include <linux/tracepoint.h>
|
2011-07-22 05:46:53 -06:00
|
|
|
#include <asm/vmx.h>
|
|
|
|
|
#include <asm/svm.h>
|
2012-11-27 18:29:01 -07:00
|
|
|
#include <asm/clocksource.h>
|
2014-11-05 12:46:42 -07:00
|
|
|
#include <asm/pvclock-abi.h>
|
2009-06-17 06:22:14 -06:00
|
|
|
|
|
|
|
|
#undef TRACE_SYSTEM
|
|
|
|
|
#define TRACE_SYSTEM kvm
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for guest mode entry.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_entry,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id),
|
|
|
|
|
TP_ARGS(vcpu_id),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu %u", __entry->vcpu_id)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for hypercall.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hypercall,
|
|
|
|
|
TP_PROTO(unsigned long nr, unsigned long a0, unsigned long a1,
|
|
|
|
|
unsigned long a2, unsigned long a3),
|
|
|
|
|
TP_ARGS(nr, a0, a1, a2, a3),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned long, nr )
|
|
|
|
|
__field( unsigned long, a0 )
|
|
|
|
|
__field( unsigned long, a1 )
|
|
|
|
|
__field( unsigned long, a2 )
|
|
|
|
|
__field( unsigned long, a3 )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->nr = nr;
|
|
|
|
|
__entry->a0 = a0;
|
|
|
|
|
__entry->a1 = a1;
|
|
|
|
|
__entry->a2 = a2;
|
|
|
|
|
__entry->a3 = a3;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("nr 0x%lx a0 0x%lx a1 0x%lx a2 0x%lx a3 0x%lx",
|
|
|
|
|
__entry->nr, __entry->a0, __entry->a1, __entry->a2,
|
|
|
|
|
__entry->a3)
|
|
|
|
|
);
|
|
|
|
|
|
2010-01-17 06:51:22 -07:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for hypercall.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_hypercall,
|
|
|
|
|
TP_PROTO(__u16 code, bool fast, __u16 rep_cnt, __u16 rep_idx,
|
|
|
|
|
__u64 ingpa, __u64 outgpa),
|
|
|
|
|
TP_ARGS(code, fast, rep_cnt, rep_idx, ingpa, outgpa),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u16, rep_cnt )
|
|
|
|
|
__field( __u16, rep_idx )
|
|
|
|
|
__field( __u64, ingpa )
|
|
|
|
|
__field( __u64, outgpa )
|
2010-12-03 17:13:21 -07:00
|
|
|
__field( __u16, code )
|
|
|
|
|
__field( bool, fast )
|
2010-01-17 06:51:22 -07:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rep_cnt = rep_cnt;
|
|
|
|
|
__entry->rep_idx = rep_idx;
|
|
|
|
|
__entry->ingpa = ingpa;
|
|
|
|
|
__entry->outgpa = outgpa;
|
2010-12-03 17:13:21 -07:00
|
|
|
__entry->code = code;
|
|
|
|
|
__entry->fast = fast;
|
2010-01-17 06:51:22 -07:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("code 0x%x %s cnt 0x%x idx 0x%x in 0x%llx out 0x%llx",
|
|
|
|
|
__entry->code, __entry->fast ? "fast" : "slow",
|
|
|
|
|
__entry->rep_cnt, __entry->rep_idx, __entry->ingpa,
|
|
|
|
|
__entry->outgpa)
|
|
|
|
|
);
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for PIO.
|
|
|
|
|
*/
|
2014-05-02 09:57:47 -06:00
|
|
|
|
|
|
|
|
#define KVM_PIO_IN 0
|
|
|
|
|
#define KVM_PIO_OUT 1
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
TRACE_EVENT(kvm_pio,
|
|
|
|
|
TP_PROTO(unsigned int rw, unsigned int port, unsigned int size,
|
2014-05-02 09:57:47 -06:00
|
|
|
unsigned int count, void *data),
|
|
|
|
|
TP_ARGS(rw, port, size, count, data),
|
2009-06-17 06:22:14 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, rw )
|
|
|
|
|
__field( unsigned int, port )
|
|
|
|
|
__field( unsigned int, size )
|
|
|
|
|
__field( unsigned int, count )
|
2014-05-02 09:57:47 -06:00
|
|
|
__field( unsigned int, val )
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rw = rw;
|
|
|
|
|
__entry->port = port;
|
|
|
|
|
__entry->size = size;
|
|
|
|
|
__entry->count = count;
|
2014-05-02 09:57:47 -06:00
|
|
|
if (size == 1)
|
|
|
|
|
__entry->val = *(unsigned char *)data;
|
|
|
|
|
else if (size == 2)
|
|
|
|
|
__entry->val = *(unsigned short *)data;
|
|
|
|
|
else
|
|
|
|
|
__entry->val = *(unsigned int *)data;
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
2014-05-02 09:57:47 -06:00
|
|
|
TP_printk("pio_%s at 0x%x size %d count %d val 0x%x %s",
|
2009-06-17 06:22:14 -06:00
|
|
|
__entry->rw ? "write" : "read",
|
2014-05-02 09:57:47 -06:00
|
|
|
__entry->port, __entry->size, __entry->count, __entry->val,
|
|
|
|
|
__entry->count > 1 ? "(...)" : "")
|
2009-06-17 06:22:14 -06:00
|
|
|
);
|
|
|
|
|
|
2015-09-15 00:41:58 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for fast mmio.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_fast_mmio,
|
|
|
|
|
TP_PROTO(u64 gpa),
|
|
|
|
|
TP_ARGS(gpa),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u64, gpa)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->gpa = gpa;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("fast mmio at gpa 0x%llx", __entry->gpa)
|
|
|
|
|
);
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for cpuid.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_cpuid,
|
|
|
|
|
TP_PROTO(unsigned int function, unsigned long rax, unsigned long rbx,
|
2017-08-24 06:27:52 -06:00
|
|
|
unsigned long rcx, unsigned long rdx, bool found),
|
|
|
|
|
TP_ARGS(function, rax, rbx, rcx, rdx, found),
|
2009-06-17 06:22:14 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, function )
|
|
|
|
|
__field( unsigned long, rax )
|
|
|
|
|
__field( unsigned long, rbx )
|
|
|
|
|
__field( unsigned long, rcx )
|
|
|
|
|
__field( unsigned long, rdx )
|
2017-08-24 06:27:52 -06:00
|
|
|
__field( bool, found )
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->function = function;
|
|
|
|
|
__entry->rax = rax;
|
|
|
|
|
__entry->rbx = rbx;
|
|
|
|
|
__entry->rcx = rcx;
|
|
|
|
|
__entry->rdx = rdx;
|
2017-08-24 06:27:52 -06:00
|
|
|
__entry->found = found;
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
2017-08-24 06:27:52 -06:00
|
|
|
TP_printk("func %x rax %lx rbx %lx rcx %lx rdx %lx, cpuid entry %s",
|
2009-06-17 06:22:14 -06:00
|
|
|
__entry->function, __entry->rax,
|
2017-08-24 06:27:52 -06:00
|
|
|
__entry->rbx, __entry->rcx, __entry->rdx,
|
|
|
|
|
__entry->found ? "found" : "not found")
|
2009-06-17 06:22:14 -06:00
|
|
|
);
|
|
|
|
|
|
2009-07-01 09:25:44 -06:00
|
|
|
#define AREG(x) { APIC_##x, "APIC_" #x }
|
|
|
|
|
|
|
|
|
|
#define kvm_trace_symbol_apic \
|
|
|
|
|
AREG(ID), AREG(LVR), AREG(TASKPRI), AREG(ARBPRI), AREG(PROCPRI), \
|
|
|
|
|
AREG(EOI), AREG(RRR), AREG(LDR), AREG(DFR), AREG(SPIV), AREG(ISR), \
|
|
|
|
|
AREG(TMR), AREG(IRR), AREG(ESR), AREG(ICR), AREG(ICR2), AREG(LVTT), \
|
|
|
|
|
AREG(LVTTHMR), AREG(LVTPC), AREG(LVT0), AREG(LVT1), AREG(LVTERR), \
|
|
|
|
|
AREG(TMICT), AREG(TMCCT), AREG(TDCR), AREG(SELF_IPI), AREG(EFEAT), \
|
|
|
|
|
AREG(ECTRL)
|
2009-06-17 06:22:14 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for apic access.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_apic,
|
|
|
|
|
TP_PROTO(unsigned int rw, unsigned int reg, unsigned int val),
|
|
|
|
|
TP_ARGS(rw, reg, val),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, rw )
|
|
|
|
|
__field( unsigned int, reg )
|
|
|
|
|
__field( unsigned int, val )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rw = rw;
|
|
|
|
|
__entry->reg = reg;
|
|
|
|
|
__entry->val = val;
|
|
|
|
|
),
|
|
|
|
|
|
2009-07-01 09:25:44 -06:00
|
|
|
TP_printk("apic_%s %s = 0x%x",
|
2009-06-17 06:22:14 -06:00
|
|
|
__entry->rw ? "write" : "read",
|
2009-07-01 09:25:44 -06:00
|
|
|
__print_symbolic(__entry->reg, kvm_trace_symbol_apic),
|
|
|
|
|
__entry->val)
|
2009-06-17 06:22:14 -06:00
|
|
|
);
|
|
|
|
|
|
|
|
|
|
#define trace_kvm_apic_read(reg, val) trace_kvm_apic(0, reg, val)
|
|
|
|
|
#define trace_kvm_apic_write(reg, val) trace_kvm_apic(1, reg, val)
|
|
|
|
|
|
2010-11-17 09:44:19 -07:00
|
|
|
#define KVM_ISA_VMX 1
|
|
|
|
|
#define KVM_ISA_SVM 2
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm guest exit:
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_exit,
|
2010-11-17 09:44:19 -07:00
|
|
|
TP_PROTO(unsigned int exit_reason, struct kvm_vcpu *vcpu, u32 isa),
|
|
|
|
|
TP_ARGS(exit_reason, vcpu, isa),
|
2009-06-17 06:22:14 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, exit_reason )
|
|
|
|
|
__field( unsigned long, guest_rip )
|
2010-11-17 09:44:19 -07:00
|
|
|
__field( u32, isa )
|
2010-11-18 04:09:54 -07:00
|
|
|
__field( u64, info1 )
|
|
|
|
|
__field( u64, info2 )
|
2019-09-05 20:17:19 -06:00
|
|
|
__field( unsigned int, vcpu_id )
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->exit_reason = exit_reason;
|
2010-03-11 01:50:44 -07:00
|
|
|
__entry->guest_rip = kvm_rip_read(vcpu);
|
2010-11-17 09:44:19 -07:00
|
|
|
__entry->isa = isa;
|
2019-09-05 20:17:19 -06:00
|
|
|
__entry->vcpu_id = vcpu->vcpu_id;
|
2010-11-18 04:09:54 -07:00
|
|
|
kvm_x86_ops->get_exit_info(vcpu, &__entry->info1,
|
|
|
|
|
&__entry->info2);
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
2019-09-05 20:17:19 -06:00
|
|
|
TP_printk("vcpu %u reason %s rip 0x%lx info %llx %llx",
|
|
|
|
|
__entry->vcpu_id,
|
2011-07-22 05:46:53 -06:00
|
|
|
(__entry->isa == KVM_ISA_VMX) ?
|
|
|
|
|
__print_symbolic(__entry->exit_reason, VMX_EXIT_REASONS) :
|
|
|
|
|
__print_symbolic(__entry->exit_reason, SVM_EXIT_REASONS),
|
2010-11-18 04:09:54 -07:00
|
|
|
__entry->guest_rip, __entry->info1, __entry->info2)
|
2009-06-17 06:22:14 -06:00
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm interrupt injection:
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_inj_virq,
|
|
|
|
|
TP_PROTO(unsigned int irq),
|
|
|
|
|
TP_ARGS(irq),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, irq )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->irq = irq;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("irq %u", __entry->irq)
|
|
|
|
|
);
|
|
|
|
|
|
2010-03-11 04:01:59 -07:00
|
|
|
#define EXS(x) { x##_VECTOR, "#" #x }
|
|
|
|
|
|
|
|
|
|
#define kvm_trace_sym_exc \
|
|
|
|
|
EXS(DE), EXS(DB), EXS(BP), EXS(OF), EXS(BR), EXS(UD), EXS(NM), \
|
|
|
|
|
EXS(DF), EXS(TS), EXS(NP), EXS(SS), EXS(GP), EXS(PF), \
|
2015-11-12 08:42:18 -07:00
|
|
|
EXS(MF), EXS(AC), EXS(MC)
|
2010-03-11 04:01:59 -07:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm interrupt injection:
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_inj_exception,
|
|
|
|
|
TP_PROTO(unsigned exception, bool has_error, unsigned error_code),
|
|
|
|
|
TP_ARGS(exception, has_error, error_code),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( u8, exception )
|
|
|
|
|
__field( u8, has_error )
|
|
|
|
|
__field( u32, error_code )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->exception = exception;
|
|
|
|
|
__entry->has_error = has_error;
|
|
|
|
|
__entry->error_code = error_code;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("%s (0x%x)",
|
|
|
|
|
__print_symbolic(__entry->exception, kvm_trace_sym_exc),
|
|
|
|
|
/* FIXME: don't print error_code if not present */
|
|
|
|
|
__entry->has_error ? __entry->error_code : 0)
|
|
|
|
|
);
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for page fault.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_page_fault,
|
|
|
|
|
TP_PROTO(unsigned long fault_address, unsigned int error_code),
|
|
|
|
|
TP_ARGS(fault_address, error_code),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned long, fault_address )
|
|
|
|
|
__field( unsigned int, error_code )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->fault_address = fault_address;
|
|
|
|
|
__entry->error_code = error_code;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("address %lx error_code %x",
|
|
|
|
|
__entry->fault_address, __entry->error_code)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for guest MSR access.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_msr,
|
2010-01-25 10:47:02 -07:00
|
|
|
TP_PROTO(unsigned write, u32 ecx, u64 data, bool exception),
|
|
|
|
|
TP_ARGS(write, ecx, data, exception),
|
2009-06-17 06:22:14 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
2010-01-25 10:36:03 -07:00
|
|
|
__field( unsigned, write )
|
|
|
|
|
__field( u32, ecx )
|
|
|
|
|
__field( u64, data )
|
2010-01-25 10:47:02 -07:00
|
|
|
__field( u8, exception )
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
2010-01-25 10:36:03 -07:00
|
|
|
__entry->write = write;
|
2009-06-17 06:22:14 -06:00
|
|
|
__entry->ecx = ecx;
|
|
|
|
|
__entry->data = data;
|
2010-01-25 10:47:02 -07:00
|
|
|
__entry->exception = exception;
|
2009-06-17 06:22:14 -06:00
|
|
|
),
|
|
|
|
|
|
2010-01-25 10:47:02 -07:00
|
|
|
TP_printk("msr_%s %x = 0x%llx%s",
|
2010-01-25 10:36:03 -07:00
|
|
|
__entry->write ? "write" : "read",
|
2010-01-25 10:47:02 -07:00
|
|
|
__entry->ecx, __entry->data,
|
|
|
|
|
__entry->exception ? " (#GP)" : "")
|
2009-06-17 06:22:14 -06:00
|
|
|
);
|
|
|
|
|
|
2010-01-25 10:47:02 -07:00
|
|
|
#define trace_kvm_msr_read(ecx, data) trace_kvm_msr(0, ecx, data, false)
|
|
|
|
|
#define trace_kvm_msr_write(ecx, data) trace_kvm_msr(1, ecx, data, false)
|
|
|
|
|
#define trace_kvm_msr_read_ex(ecx) trace_kvm_msr(0, ecx, 0, true)
|
|
|
|
|
#define trace_kvm_msr_write_ex(ecx, data) trace_kvm_msr(1, ecx, data, true)
|
2009-06-17 06:22:14 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for guest CR access.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_cr,
|
|
|
|
|
TP_PROTO(unsigned int rw, unsigned int cr, unsigned long val),
|
|
|
|
|
TP_ARGS(rw, cr, val),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, rw )
|
|
|
|
|
__field( unsigned int, cr )
|
|
|
|
|
__field( unsigned long, val )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rw = rw;
|
|
|
|
|
__entry->cr = cr;
|
|
|
|
|
__entry->val = val;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("cr_%s %x = 0x%lx",
|
|
|
|
|
__entry->rw ? "write" : "read",
|
|
|
|
|
__entry->cr, __entry->val)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
#define trace_kvm_cr_read(cr, val) trace_kvm_cr(0, cr, val)
|
|
|
|
|
#define trace_kvm_cr_write(cr, val) trace_kvm_cr(1, cr, val)
|
|
|
|
|
|
2009-07-07 07:00:57 -06:00
|
|
|
TRACE_EVENT(kvm_pic_set_irq,
|
|
|
|
|
TP_PROTO(__u8 chip, __u8 pin, __u8 elcr, __u8 imr, bool coalesced),
|
|
|
|
|
TP_ARGS(chip, pin, elcr, imr, coalesced),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u8, chip )
|
|
|
|
|
__field( __u8, pin )
|
|
|
|
|
__field( __u8, elcr )
|
|
|
|
|
__field( __u8, imr )
|
|
|
|
|
__field( bool, coalesced )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->chip = chip;
|
|
|
|
|
__entry->pin = pin;
|
|
|
|
|
__entry->elcr = elcr;
|
|
|
|
|
__entry->imr = imr;
|
|
|
|
|
__entry->coalesced = coalesced;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("chip %u pin %u (%s%s)%s",
|
|
|
|
|
__entry->chip, __entry->pin,
|
|
|
|
|
(__entry->elcr & (1 << __entry->pin)) ? "level":"edge",
|
|
|
|
|
(__entry->imr & (1 << __entry->pin)) ? "|masked":"",
|
|
|
|
|
__entry->coalesced ? " (coalesced)" : "")
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
#define kvm_apic_dst_shorthand \
|
|
|
|
|
{0x0, "dst"}, \
|
|
|
|
|
{0x1, "self"}, \
|
|
|
|
|
{0x2, "all"}, \
|
|
|
|
|
{0x3, "all-but-self"}
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_apic_ipi,
|
|
|
|
|
TP_PROTO(__u32 icr_low, __u32 dest_id),
|
|
|
|
|
TP_ARGS(icr_low, dest_id),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u32, icr_low )
|
|
|
|
|
__field( __u32, dest_id )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->icr_low = icr_low;
|
|
|
|
|
__entry->dest_id = dest_id;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("dst %x vec %u (%s|%s|%s|%s|%s)",
|
|
|
|
|
__entry->dest_id, (u8)__entry->icr_low,
|
|
|
|
|
__print_symbolic((__entry->icr_low >> 8 & 0x7),
|
|
|
|
|
kvm_deliver_mode),
|
|
|
|
|
(__entry->icr_low & (1<<11)) ? "logical" : "physical",
|
|
|
|
|
(__entry->icr_low & (1<<14)) ? "assert" : "de-assert",
|
|
|
|
|
(__entry->icr_low & (1<<15)) ? "level" : "edge",
|
|
|
|
|
__print_symbolic((__entry->icr_low >> 18 & 0x3),
|
|
|
|
|
kvm_apic_dst_shorthand))
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_apic_accept_irq,
|
2019-03-27 08:12:20 -06:00
|
|
|
TP_PROTO(__u32 apicid, __u16 dm, __u16 tm, __u8 vec),
|
2014-09-11 03:51:02 -06:00
|
|
|
TP_ARGS(apicid, dm, tm, vec),
|
2009-07-07 07:00:57 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u32, apicid )
|
|
|
|
|
__field( __u16, dm )
|
2019-03-27 08:12:20 -06:00
|
|
|
__field( __u16, tm )
|
2009-07-07 07:00:57 -06:00
|
|
|
__field( __u8, vec )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->apicid = apicid;
|
|
|
|
|
__entry->dm = dm;
|
|
|
|
|
__entry->tm = tm;
|
|
|
|
|
__entry->vec = vec;
|
|
|
|
|
),
|
|
|
|
|
|
2014-09-11 03:51:02 -06:00
|
|
|
TP_printk("apicid %x vec %u (%s|%s)",
|
2009-07-07 07:00:57 -06:00
|
|
|
__entry->apicid, __entry->vec,
|
|
|
|
|
__print_symbolic((__entry->dm >> 8 & 0x7), kvm_deliver_mode),
|
2014-09-11 03:51:02 -06:00
|
|
|
__entry->tm ? "level" : "edge")
|
2009-07-07 07:00:57 -06:00
|
|
|
);
|
|
|
|
|
|
2012-06-24 10:25:07 -06:00
|
|
|
TRACE_EVENT(kvm_eoi,
|
|
|
|
|
TP_PROTO(struct kvm_lapic *apic, int vector),
|
|
|
|
|
TP_ARGS(apic, vector),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u32, apicid )
|
|
|
|
|
__field( int, vector )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->apicid = apic->vcpu->vcpu_id;
|
|
|
|
|
__entry->vector = vector;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("apicid %x vector %d", __entry->apicid, __entry->vector)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_pv_eoi,
|
|
|
|
|
TP_PROTO(struct kvm_lapic *apic, int vector),
|
|
|
|
|
TP_ARGS(apic, vector),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u32, apicid )
|
|
|
|
|
__field( int, vector )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->apicid = apic->vcpu->vcpu_id;
|
|
|
|
|
__entry->vector = vector;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("apicid %x vector %d", __entry->apicid, __entry->vector)
|
|
|
|
|
);
|
|
|
|
|
|
2009-10-09 08:08:27 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for nested VMRUN
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_nested_vmrun,
|
|
|
|
|
TP_PROTO(__u64 rip, __u64 vmcb, __u64 nested_rip, __u32 int_ctl,
|
|
|
|
|
__u32 event_inj, bool npt),
|
|
|
|
|
TP_ARGS(rip, vmcb, nested_rip, int_ctl, event_inj, npt),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u64, rip )
|
|
|
|
|
__field( __u64, vmcb )
|
|
|
|
|
__field( __u64, nested_rip )
|
|
|
|
|
__field( __u32, int_ctl )
|
|
|
|
|
__field( __u32, event_inj )
|
|
|
|
|
__field( bool, npt )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rip = rip;
|
|
|
|
|
__entry->vmcb = vmcb;
|
|
|
|
|
__entry->nested_rip = nested_rip;
|
|
|
|
|
__entry->int_ctl = int_ctl;
|
|
|
|
|
__entry->event_inj = event_inj;
|
|
|
|
|
__entry->npt = npt;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("rip: 0x%016llx vmcb: 0x%016llx nrip: 0x%016llx int_ctl: 0x%08x "
|
2010-02-19 08:23:09 -07:00
|
|
|
"event_inj: 0x%08x npt: %s",
|
2009-10-09 08:08:27 -06:00
|
|
|
__entry->rip, __entry->vmcb, __entry->nested_rip,
|
|
|
|
|
__entry->int_ctl, __entry->event_inj,
|
|
|
|
|
__entry->npt ? "on" : "off")
|
|
|
|
|
);
|
|
|
|
|
|
2010-02-24 10:59:14 -07:00
|
|
|
TRACE_EVENT(kvm_nested_intercepts,
|
|
|
|
|
TP_PROTO(__u16 cr_read, __u16 cr_write, __u32 exceptions, __u64 intercept),
|
|
|
|
|
TP_ARGS(cr_read, cr_write, exceptions, intercept),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u16, cr_read )
|
|
|
|
|
__field( __u16, cr_write )
|
|
|
|
|
__field( __u32, exceptions )
|
|
|
|
|
__field( __u64, intercept )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->cr_read = cr_read;
|
|
|
|
|
__entry->cr_write = cr_write;
|
|
|
|
|
__entry->exceptions = exceptions;
|
|
|
|
|
__entry->intercept = intercept;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("cr_read: %04x cr_write: %04x excp: %08x intercept: %016llx",
|
|
|
|
|
__entry->cr_read, __entry->cr_write, __entry->exceptions,
|
|
|
|
|
__entry->intercept)
|
|
|
|
|
);
|
2009-10-09 08:08:28 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for #VMEXIT while nested
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_nested_vmexit,
|
|
|
|
|
TP_PROTO(__u64 rip, __u32 exit_code,
|
|
|
|
|
__u64 exit_info1, __u64 exit_info2,
|
2011-07-22 05:46:52 -06:00
|
|
|
__u32 exit_int_info, __u32 exit_int_info_err, __u32 isa),
|
2009-10-09 08:08:28 -06:00
|
|
|
TP_ARGS(rip, exit_code, exit_info1, exit_info2,
|
2011-07-22 05:46:52 -06:00
|
|
|
exit_int_info, exit_int_info_err, isa),
|
2009-10-09 08:08:28 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u64, rip )
|
|
|
|
|
__field( __u32, exit_code )
|
|
|
|
|
__field( __u64, exit_info1 )
|
|
|
|
|
__field( __u64, exit_info2 )
|
|
|
|
|
__field( __u32, exit_int_info )
|
|
|
|
|
__field( __u32, exit_int_info_err )
|
2011-07-22 05:46:52 -06:00
|
|
|
__field( __u32, isa )
|
2009-10-09 08:08:28 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rip = rip;
|
|
|
|
|
__entry->exit_code = exit_code;
|
|
|
|
|
__entry->exit_info1 = exit_info1;
|
|
|
|
|
__entry->exit_info2 = exit_info2;
|
|
|
|
|
__entry->exit_int_info = exit_int_info;
|
|
|
|
|
__entry->exit_int_info_err = exit_int_info_err;
|
2011-07-22 05:46:52 -06:00
|
|
|
__entry->isa = isa;
|
2009-10-09 08:08:28 -06:00
|
|
|
),
|
|
|
|
|
TP_printk("rip: 0x%016llx reason: %s ext_inf1: 0x%016llx "
|
2010-02-19 08:23:09 -07:00
|
|
|
"ext_inf2: 0x%016llx ext_int: 0x%08x ext_int_err: 0x%08x",
|
2009-10-09 08:08:28 -06:00
|
|
|
__entry->rip,
|
2011-07-22 05:46:53 -06:00
|
|
|
(__entry->isa == KVM_ISA_VMX) ?
|
|
|
|
|
__print_symbolic(__entry->exit_code, VMX_EXIT_REASONS) :
|
|
|
|
|
__print_symbolic(__entry->exit_code, SVM_EXIT_REASONS),
|
2009-10-09 08:08:28 -06:00
|
|
|
__entry->exit_info1, __entry->exit_info2,
|
|
|
|
|
__entry->exit_int_info, __entry->exit_int_info_err)
|
|
|
|
|
);
|
|
|
|
|
|
2009-10-09 08:08:29 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for #VMEXIT reinjected to the guest
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_nested_vmexit_inject,
|
|
|
|
|
TP_PROTO(__u32 exit_code,
|
|
|
|
|
__u64 exit_info1, __u64 exit_info2,
|
2011-07-22 05:46:52 -06:00
|
|
|
__u32 exit_int_info, __u32 exit_int_info_err, __u32 isa),
|
2009-10-09 08:08:29 -06:00
|
|
|
TP_ARGS(exit_code, exit_info1, exit_info2,
|
2011-07-22 05:46:52 -06:00
|
|
|
exit_int_info, exit_int_info_err, isa),
|
2009-10-09 08:08:29 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u32, exit_code )
|
|
|
|
|
__field( __u64, exit_info1 )
|
|
|
|
|
__field( __u64, exit_info2 )
|
|
|
|
|
__field( __u32, exit_int_info )
|
|
|
|
|
__field( __u32, exit_int_info_err )
|
2011-07-22 05:46:52 -06:00
|
|
|
__field( __u32, isa )
|
2009-10-09 08:08:29 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->exit_code = exit_code;
|
|
|
|
|
__entry->exit_info1 = exit_info1;
|
|
|
|
|
__entry->exit_info2 = exit_info2;
|
|
|
|
|
__entry->exit_int_info = exit_int_info;
|
|
|
|
|
__entry->exit_int_info_err = exit_int_info_err;
|
2011-07-22 05:46:52 -06:00
|
|
|
__entry->isa = isa;
|
2009-10-09 08:08:29 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("reason: %s ext_inf1: 0x%016llx "
|
2010-02-19 08:23:09 -07:00
|
|
|
"ext_inf2: 0x%016llx ext_int: 0x%08x ext_int_err: 0x%08x",
|
2011-07-22 05:46:53 -06:00
|
|
|
(__entry->isa == KVM_ISA_VMX) ?
|
|
|
|
|
__print_symbolic(__entry->exit_code, VMX_EXIT_REASONS) :
|
|
|
|
|
__print_symbolic(__entry->exit_code, SVM_EXIT_REASONS),
|
2009-10-09 08:08:29 -06:00
|
|
|
__entry->exit_info1, __entry->exit_info2,
|
|
|
|
|
__entry->exit_int_info, __entry->exit_int_info_err)
|
|
|
|
|
);
|
2009-10-09 08:08:30 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for nested #vmexit because of interrupt pending
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_nested_intr_vmexit,
|
|
|
|
|
TP_PROTO(__u64 rip),
|
|
|
|
|
TP_ARGS(rip),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u64, rip )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rip = rip
|
|
|
|
|
),
|
|
|
|
|
|
2010-02-19 08:23:09 -07:00
|
|
|
TP_printk("rip: 0x%016llx", __entry->rip)
|
2009-10-09 08:08:30 -06:00
|
|
|
);
|
2009-10-09 08:08:31 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for nested #vmexit because of interrupt pending
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_invlpga,
|
|
|
|
|
TP_PROTO(__u64 rip, int asid, u64 address),
|
|
|
|
|
TP_ARGS(rip, asid, address),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u64, rip )
|
|
|
|
|
__field( int, asid )
|
|
|
|
|
__field( __u64, address )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rip = rip;
|
|
|
|
|
__entry->asid = asid;
|
|
|
|
|
__entry->address = address;
|
|
|
|
|
),
|
|
|
|
|
|
2010-02-19 08:23:09 -07:00
|
|
|
TP_printk("rip: 0x%016llx asid: %d address: 0x%016llx",
|
2009-10-09 08:08:31 -06:00
|
|
|
__entry->rip, __entry->asid, __entry->address)
|
|
|
|
|
);
|
2009-10-09 08:08:32 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for nested #vmexit because of interrupt pending
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_skinit,
|
|
|
|
|
TP_PROTO(__u64 rip, __u32 slb),
|
|
|
|
|
TP_ARGS(rip, slb),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u64, rip )
|
|
|
|
|
__field( __u32, slb )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->rip = rip;
|
|
|
|
|
__entry->slb = slb;
|
|
|
|
|
),
|
|
|
|
|
|
2010-02-19 08:23:09 -07:00
|
|
|
TP_printk("rip: 0x%016llx slb: 0x%08x",
|
2009-10-09 08:08:32 -06:00
|
|
|
__entry->rip, __entry->slb)
|
|
|
|
|
);
|
|
|
|
|
|
2010-04-11 04:05:16 -06:00
|
|
|
#define KVM_EMUL_INSN_F_CR0_PE (1 << 0)
|
|
|
|
|
#define KVM_EMUL_INSN_F_EFL_VM (1 << 1)
|
|
|
|
|
#define KVM_EMUL_INSN_F_CS_D (1 << 2)
|
|
|
|
|
#define KVM_EMUL_INSN_F_CS_L (1 << 3)
|
|
|
|
|
|
|
|
|
|
#define kvm_trace_symbol_emul_flags \
|
|
|
|
|
{ 0, "real" }, \
|
|
|
|
|
{ KVM_EMUL_INSN_F_CR0_PE \
|
|
|
|
|
| KVM_EMUL_INSN_F_EFL_VM, "vm16" }, \
|
|
|
|
|
{ KVM_EMUL_INSN_F_CR0_PE, "prot16" }, \
|
|
|
|
|
{ KVM_EMUL_INSN_F_CR0_PE \
|
|
|
|
|
| KVM_EMUL_INSN_F_CS_D, "prot32" }, \
|
|
|
|
|
{ KVM_EMUL_INSN_F_CR0_PE \
|
|
|
|
|
| KVM_EMUL_INSN_F_CS_L, "prot64" }
|
|
|
|
|
|
|
|
|
|
#define kei_decode_mode(mode) ({ \
|
|
|
|
|
u8 flags = 0xff; \
|
|
|
|
|
switch (mode) { \
|
|
|
|
|
case X86EMUL_MODE_REAL: \
|
|
|
|
|
flags = 0; \
|
|
|
|
|
break; \
|
|
|
|
|
case X86EMUL_MODE_VM86: \
|
|
|
|
|
flags = KVM_EMUL_INSN_F_EFL_VM; \
|
|
|
|
|
break; \
|
|
|
|
|
case X86EMUL_MODE_PROT16: \
|
|
|
|
|
flags = KVM_EMUL_INSN_F_CR0_PE; \
|
|
|
|
|
break; \
|
|
|
|
|
case X86EMUL_MODE_PROT32: \
|
|
|
|
|
flags = KVM_EMUL_INSN_F_CR0_PE \
|
|
|
|
|
| KVM_EMUL_INSN_F_CS_D; \
|
|
|
|
|
break; \
|
|
|
|
|
case X86EMUL_MODE_PROT64: \
|
|
|
|
|
flags = KVM_EMUL_INSN_F_CR0_PE \
|
|
|
|
|
| KVM_EMUL_INSN_F_CS_L; \
|
|
|
|
|
break; \
|
|
|
|
|
} \
|
|
|
|
|
flags; \
|
|
|
|
|
})
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_emulate_insn,
|
|
|
|
|
TP_PROTO(struct kvm_vcpu *vcpu, __u8 failed),
|
|
|
|
|
TP_ARGS(vcpu, failed),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( __u64, rip )
|
|
|
|
|
__field( __u32, csbase )
|
|
|
|
|
__field( __u8, len )
|
|
|
|
|
__array( __u8, insn, 15 )
|
|
|
|
|
__field( __u8, flags )
|
|
|
|
|
__field( __u8, failed )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->csbase = kvm_x86_ops->get_segment_base(vcpu, VCPU_SREG_CS);
|
2014-05-06 08:33:01 -06:00
|
|
|
__entry->len = vcpu->arch.emulate_ctxt.fetch.ptr
|
|
|
|
|
- vcpu->arch.emulate_ctxt.fetch.data;
|
|
|
|
|
__entry->rip = vcpu->arch.emulate_ctxt._eip - __entry->len;
|
2010-04-11 04:05:16 -06:00
|
|
|
memcpy(__entry->insn,
|
2011-06-01 06:34:25 -06:00
|
|
|
vcpu->arch.emulate_ctxt.fetch.data,
|
2010-04-11 04:05:16 -06:00
|
|
|
15);
|
|
|
|
|
__entry->flags = kei_decode_mode(vcpu->arch.emulate_ctxt.mode);
|
|
|
|
|
__entry->failed = failed;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("%x:%llx:%s (%s)%s",
|
|
|
|
|
__entry->csbase, __entry->rip,
|
tracing/kvm: Use __print_hex() for kvm_emulate_insn tracepoint
The kvm_emulate_insn tracepoint used __print_insn()
for printing its instructions. However it makes the
format of the event hard to parse as it reveals TP
internals.
Fortunately, kernel provides __print_hex for almost
same purpose, we can use it instead of open coding
it. The user-space can be changed to parse it later.
That means raw kernel tracing will not be affected
by this change:
# cd /sys/kernel/debug/tracing/
# cat events/kvm/kvm_emulate_insn/format
name: kvm_emulate_insn
ID: 29
format:
...
print fmt: "%x:%llx:%s (%s)%s", REC->csbase, REC->rip, __print_hex(REC->insn, REC->len), \
__print_symbolic(REC->flags, { 0, "real" }, { (1 << 0) | (1 << 1), "vm16" }, \
{ (1 << 0), "prot16" }, { (1 << 0) | (1 << 2), "prot32" }, { (1 << 0) | (1 << 3), "prot64" }), \
REC->failed ? " failed" : ""
# echo 1 > events/kvm/kvm_emulate_insn/enable
# cat trace
# tracer: nop
#
# entries-in-buffer/entries-written: 2183/2183 #P:12
#
# _-----=> irqs-off
# / _----=> need-resched
# | / _---=> hardirq/softirq
# || / _--=> preempt-depth
# ||| / delay
# TASK-PID CPU# |||| TIMESTAMP FUNCTION
# | | | |||| | |
qemu-kvm-1782 [002] ...1 140.931636: kvm_emulate_insn: 0:c102fa25:89 10 (prot32)
qemu-kvm-1781 [004] ...1 140.931637: kvm_emulate_insn: 0:c102fa25:89 10 (prot32)
Link: http://lkml.kernel.org/n/tip-wfw6y3b9ugtey8snaow9nmg5@git.kernel.org
Link: http://lkml.kernel.org/r/1340757701-10711-2-git-send-email-namhyung@kernel.org
Cc: Arnaldo Carvalho de Melo <acme@ghostprotocols.net>
Cc: Frederic Weisbecker <fweisbec@gmail.com>
Cc: Peter Zijlstra <a.p.zijlstra@chello.nl>
Cc: Ingo Molnar <mingo@kernel.org>
Cc: Namhyung Kim <namhyung.kim@lge.com>
Cc: kvm@vger.kernel.org
Acked-by: Avi Kivity <avi@redhat.com>
Signed-off-by: Namhyung Kim <namhyung@kernel.org>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2012-06-26 18:41:39 -06:00
|
|
|
__print_hex(__entry->insn, __entry->len),
|
2010-04-11 04:05:16 -06:00
|
|
|
__print_symbolic(__entry->flags,
|
|
|
|
|
kvm_trace_symbol_emul_flags),
|
|
|
|
|
__entry->failed ? " failed" : ""
|
|
|
|
|
)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
#define trace_kvm_emulate_insn_start(vcpu) trace_kvm_emulate_insn(vcpu, 0)
|
|
|
|
|
#define trace_kvm_emulate_insn_failed(vcpu) trace_kvm_emulate_insn(vcpu, 1)
|
|
|
|
|
|
2011-07-11 13:34:24 -06:00
|
|
|
TRACE_EVENT(
|
|
|
|
|
vcpu_match_mmio,
|
|
|
|
|
TP_PROTO(gva_t gva, gpa_t gpa, bool write, bool gpa_match),
|
|
|
|
|
TP_ARGS(gva, gpa, write, gpa_match),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(gva_t, gva)
|
|
|
|
|
__field(gpa_t, gpa)
|
|
|
|
|
__field(bool, write)
|
|
|
|
|
__field(bool, gpa_match)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->gva = gva;
|
|
|
|
|
__entry->gpa = gpa;
|
|
|
|
|
__entry->write = write;
|
|
|
|
|
__entry->gpa_match = gpa_match
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("gva %#lx gpa %#llx %s %s", __entry->gva, __entry->gpa,
|
|
|
|
|
__entry->write ? "Write" : "Read",
|
|
|
|
|
__entry->gpa_match ? "GPA" : "GVA")
|
|
|
|
|
);
|
2012-11-27 18:29:01 -07:00
|
|
|
|
2013-06-12 01:43:44 -06:00
|
|
|
TRACE_EVENT(kvm_write_tsc_offset,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, __u64 previous_tsc_offset,
|
|
|
|
|
__u64 next_tsc_offset),
|
|
|
|
|
TP_ARGS(vcpu_id, previous_tsc_offset, next_tsc_offset),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( __u64, previous_tsc_offset )
|
|
|
|
|
__field( __u64, next_tsc_offset )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->previous_tsc_offset = previous_tsc_offset;
|
|
|
|
|
__entry->next_tsc_offset = next_tsc_offset;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu=%u prev=%llu next=%llu", __entry->vcpu_id,
|
|
|
|
|
__entry->previous_tsc_offset, __entry->next_tsc_offset)
|
|
|
|
|
);
|
|
|
|
|
|
2012-11-27 18:29:01 -07:00
|
|
|
#ifdef CONFIG_X86_64
|
|
|
|
|
|
|
|
|
|
#define host_clocks \
|
|
|
|
|
{VCLOCK_NONE, "none"}, \
|
2016-04-07 18:16:59 -06:00
|
|
|
{VCLOCK_TSC, "tsc"} \
|
2012-11-27 18:29:01 -07:00
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_update_master_clock,
|
2012-11-27 18:29:03 -07:00
|
|
|
TP_PROTO(bool use_master_clock, unsigned int host_clock, bool offset_matched),
|
|
|
|
|
TP_ARGS(use_master_clock, host_clock, offset_matched),
|
2012-11-27 18:29:01 -07:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( bool, use_master_clock )
|
|
|
|
|
__field( unsigned int, host_clock )
|
2012-11-27 18:29:03 -07:00
|
|
|
__field( bool, offset_matched )
|
2012-11-27 18:29:01 -07:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->use_master_clock = use_master_clock;
|
|
|
|
|
__entry->host_clock = host_clock;
|
2012-11-27 18:29:03 -07:00
|
|
|
__entry->offset_matched = offset_matched;
|
2012-11-27 18:29:01 -07:00
|
|
|
),
|
|
|
|
|
|
2012-11-27 18:29:03 -07:00
|
|
|
TP_printk("masterclock %d hostclock %s offsetmatched %u",
|
2012-11-27 18:29:01 -07:00
|
|
|
__entry->use_master_clock,
|
2012-11-27 18:29:03 -07:00
|
|
|
__print_symbolic(__entry->host_clock, host_clocks),
|
|
|
|
|
__entry->offset_matched)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_track_tsc,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, unsigned int nr_matched,
|
|
|
|
|
unsigned int online_vcpus, bool use_master_clock,
|
|
|
|
|
unsigned int host_clock),
|
|
|
|
|
TP_ARGS(vcpu_id, nr_matched, online_vcpus, use_master_clock,
|
|
|
|
|
host_clock),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( unsigned int, nr_vcpus_matched_tsc )
|
|
|
|
|
__field( unsigned int, online_vcpus )
|
|
|
|
|
__field( bool, use_master_clock )
|
|
|
|
|
__field( unsigned int, host_clock )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->nr_vcpus_matched_tsc = nr_matched;
|
|
|
|
|
__entry->online_vcpus = online_vcpus;
|
|
|
|
|
__entry->use_master_clock = use_master_clock;
|
|
|
|
|
__entry->host_clock = host_clock;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %u masterclock %u offsetmatched %u nr_online %u"
|
|
|
|
|
" hostclock %s",
|
|
|
|
|
__entry->vcpu_id, __entry->use_master_clock,
|
|
|
|
|
__entry->nr_vcpus_matched_tsc, __entry->online_vcpus,
|
2012-11-27 18:29:01 -07:00
|
|
|
__print_symbolic(__entry->host_clock, host_clocks))
|
|
|
|
|
);
|
|
|
|
|
|
2014-08-25 08:08:21 -06:00
|
|
|
#endif /* CONFIG_X86_64 */
|
|
|
|
|
|
2015-01-27 19:54:28 -07:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for PML full VMEXIT.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_pml_full,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id),
|
|
|
|
|
TP_ARGS(vcpu_id),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu %d: PML full", __entry->vcpu_id)
|
|
|
|
|
);
|
|
|
|
|
|
2019-09-05 20:17:22 -06:00
|
|
|
TRACE_EVENT(kvm_ple_window_update,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, unsigned int new, unsigned int old),
|
|
|
|
|
TP_ARGS(vcpu_id, new, old),
|
2014-08-21 10:08:09 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
2019-09-05 20:17:21 -06:00
|
|
|
__field( unsigned int, new )
|
|
|
|
|
__field( unsigned int, old )
|
2014-08-21 10:08:09 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->new = new;
|
|
|
|
|
__entry->old = old;
|
|
|
|
|
),
|
|
|
|
|
|
2019-09-05 20:17:22 -06:00
|
|
|
TP_printk("vcpu %u old %u new %u (%s)",
|
|
|
|
|
__entry->vcpu_id, __entry->old, __entry->new,
|
|
|
|
|
__entry->old < __entry->new ? "growed" : "shrinked")
|
2014-08-21 10:08:09 -06:00
|
|
|
);
|
|
|
|
|
|
2014-11-05 12:46:42 -07:00
|
|
|
TRACE_EVENT(kvm_pvclock_update,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, struct pvclock_vcpu_time_info *pvclock),
|
|
|
|
|
TP_ARGS(vcpu_id, pvclock),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( __u32, version )
|
|
|
|
|
__field( __u64, tsc_timestamp )
|
|
|
|
|
__field( __u64, system_time )
|
|
|
|
|
__field( __u32, tsc_to_system_mul )
|
|
|
|
|
__field( __s8, tsc_shift )
|
|
|
|
|
__field( __u8, flags )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->version = pvclock->version;
|
|
|
|
|
__entry->tsc_timestamp = pvclock->tsc_timestamp;
|
|
|
|
|
__entry->system_time = pvclock->system_time;
|
|
|
|
|
__entry->tsc_to_system_mul = pvclock->tsc_to_system_mul;
|
|
|
|
|
__entry->tsc_shift = pvclock->tsc_shift;
|
|
|
|
|
__entry->flags = pvclock->flags;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %u, pvclock { version %u, tsc_timestamp 0x%llx, "
|
|
|
|
|
"system_time 0x%llx, tsc_to_system_mul 0x%x, tsc_shift %d, "
|
|
|
|
|
"flags 0x%x }",
|
|
|
|
|
__entry->vcpu_id,
|
|
|
|
|
__entry->version,
|
|
|
|
|
__entry->tsc_timestamp,
|
|
|
|
|
__entry->system_time,
|
|
|
|
|
__entry->tsc_to_system_mul,
|
|
|
|
|
__entry->tsc_shift,
|
|
|
|
|
__entry->flags)
|
|
|
|
|
);
|
|
|
|
|
|
2014-12-16 07:08:16 -07:00
|
|
|
TRACE_EVENT(kvm_wait_lapic_expire,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, s64 delta),
|
|
|
|
|
TP_ARGS(vcpu_id, delta),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( s64, delta )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->delta = delta;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu %u: delta %lld (%s)",
|
|
|
|
|
__entry->vcpu_id,
|
|
|
|
|
__entry->delta,
|
|
|
|
|
__entry->delta < 0 ? "early" : "late")
|
|
|
|
|
);
|
|
|
|
|
|
2015-05-05 03:50:23 -06:00
|
|
|
TRACE_EVENT(kvm_enter_smm,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, u64 smbase, bool entering),
|
|
|
|
|
TP_ARGS(vcpu_id, smbase, entering),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( u64, smbase )
|
|
|
|
|
__field( bool, entering )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->smbase = smbase;
|
|
|
|
|
__entry->entering = entering;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu %u: %s SMM, smbase 0x%llx",
|
|
|
|
|
__entry->vcpu_id,
|
|
|
|
|
__entry->entering ? "entering" : "leaving",
|
|
|
|
|
__entry->smbase)
|
|
|
|
|
);
|
|
|
|
|
|
2015-09-18 08:29:51 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for VT-d posted-interrupts.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_pi_irte_update,
|
2016-01-25 01:53:35 -07:00
|
|
|
TP_PROTO(unsigned int host_irq, unsigned int vcpu_id,
|
|
|
|
|
unsigned int gsi, unsigned int gvec,
|
|
|
|
|
u64 pi_desc_addr, bool set),
|
|
|
|
|
TP_ARGS(host_irq, vcpu_id, gsi, gvec, pi_desc_addr, set),
|
2015-09-18 08:29:51 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
2016-01-25 01:53:35 -07:00
|
|
|
__field( unsigned int, host_irq )
|
2015-09-18 08:29:51 -06:00
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( unsigned int, gsi )
|
|
|
|
|
__field( unsigned int, gvec )
|
|
|
|
|
__field( u64, pi_desc_addr )
|
|
|
|
|
__field( bool, set )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
2016-01-25 01:53:35 -07:00
|
|
|
__entry->host_irq = host_irq;
|
2015-09-18 08:29:51 -06:00
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->gsi = gsi;
|
|
|
|
|
__entry->gvec = gvec;
|
|
|
|
|
__entry->pi_desc_addr = pi_desc_addr;
|
|
|
|
|
__entry->set = set;
|
|
|
|
|
),
|
|
|
|
|
|
2016-01-25 01:53:35 -07:00
|
|
|
TP_printk("VT-d PI is %s for irq %u, vcpu %u, gsi: 0x%x, "
|
2015-09-18 08:29:51 -06:00
|
|
|
"gvec: 0x%x, pi_desc_addr: 0x%llx",
|
|
|
|
|
__entry->set ? "enabled and being updated" : "disabled",
|
2016-01-25 01:53:35 -07:00
|
|
|
__entry->host_irq,
|
2015-09-18 08:29:51 -06:00
|
|
|
__entry->vcpu_id,
|
|
|
|
|
__entry->gsi,
|
|
|
|
|
__entry->gvec,
|
|
|
|
|
__entry->pi_desc_addr)
|
|
|
|
|
);
|
|
|
|
|
|
2015-12-23 06:53:59 -07:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm_hv_notify_acked_sint.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_notify_acked_sint,
|
|
|
|
|
TP_PROTO(int vcpu_id, u32 sint),
|
|
|
|
|
TP_ARGS(vcpu_id, sint),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(u32, sint)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->sint = sint;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d sint %u", __entry->vcpu_id, __entry->sint)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for synic_set_irq.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_synic_set_irq,
|
|
|
|
|
TP_PROTO(int vcpu_id, u32 sint, int vector, int ret),
|
|
|
|
|
TP_ARGS(vcpu_id, sint, vector, ret),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(u32, sint)
|
|
|
|
|
__field(int, vector)
|
|
|
|
|
__field(int, ret)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->sint = sint;
|
|
|
|
|
__entry->vector = vector;
|
|
|
|
|
__entry->ret = ret;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d sint %u vector %d ret %d",
|
|
|
|
|
__entry->vcpu_id, __entry->sint, __entry->vector,
|
|
|
|
|
__entry->ret)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm_hv_synic_send_eoi.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_synic_send_eoi,
|
|
|
|
|
TP_PROTO(int vcpu_id, int vector),
|
|
|
|
|
TP_ARGS(vcpu_id, vector),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(u32, sint)
|
|
|
|
|
__field(int, vector)
|
|
|
|
|
__field(int, ret)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->vector = vector;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d vector %d", __entry->vcpu_id, __entry->vector)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for synic_set_msr.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_synic_set_msr,
|
|
|
|
|
TP_PROTO(int vcpu_id, u32 msr, u64 data, bool host),
|
|
|
|
|
TP_ARGS(vcpu_id, msr, data, host),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(u32, msr)
|
|
|
|
|
__field(u64, data)
|
|
|
|
|
__field(bool, host)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->msr = msr;
|
|
|
|
|
__entry->data = data;
|
|
|
|
|
__entry->host = host
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d msr 0x%x data 0x%llx host %d",
|
|
|
|
|
__entry->vcpu_id, __entry->msr, __entry->data, __entry->host)
|
|
|
|
|
);
|
|
|
|
|
|
2015-12-23 06:54:00 -07:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_set_config.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_set_config,
|
|
|
|
|
TP_PROTO(int vcpu_id, int timer_index, u64 config, bool host),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index, config, host),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
|
|
|
|
__field(u64, config)
|
|
|
|
|
__field(bool, host)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
|
|
|
|
__entry->config = config;
|
|
|
|
|
__entry->host = host;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d timer %d config 0x%llx host %d",
|
|
|
|
|
__entry->vcpu_id, __entry->timer_index, __entry->config,
|
|
|
|
|
__entry->host)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_set_count.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_set_count,
|
|
|
|
|
TP_PROTO(int vcpu_id, int timer_index, u64 count, bool host),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index, count, host),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
|
|
|
|
__field(u64, count)
|
|
|
|
|
__field(bool, host)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
|
|
|
|
__entry->count = count;
|
|
|
|
|
__entry->host = host;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d timer %d count %llu host %d",
|
|
|
|
|
__entry->vcpu_id, __entry->timer_index, __entry->count,
|
|
|
|
|
__entry->host)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_start(periodic timer case).
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_start_periodic,
|
|
|
|
|
TP_PROTO(int vcpu_id, int timer_index, u64 time_now, u64 exp_time),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index, time_now, exp_time),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
|
|
|
|
__field(u64, time_now)
|
|
|
|
|
__field(u64, exp_time)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
|
|
|
|
__entry->time_now = time_now;
|
|
|
|
|
__entry->exp_time = exp_time;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d timer %d time_now %llu exp_time %llu",
|
|
|
|
|
__entry->vcpu_id, __entry->timer_index, __entry->time_now,
|
|
|
|
|
__entry->exp_time)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_start(one-shot timer case).
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_start_one_shot,
|
|
|
|
|
TP_PROTO(int vcpu_id, int timer_index, u64 time_now, u64 count),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index, time_now, count),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
|
|
|
|
__field(u64, time_now)
|
|
|
|
|
__field(u64, count)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
|
|
|
|
__entry->time_now = time_now;
|
|
|
|
|
__entry->count = count;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d timer %d time_now %llu count %llu",
|
|
|
|
|
__entry->vcpu_id, __entry->timer_index, __entry->time_now,
|
|
|
|
|
__entry->count)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_timer_callback.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_callback,
|
|
|
|
|
TP_PROTO(int vcpu_id, int timer_index),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d timer %d",
|
|
|
|
|
__entry->vcpu_id, __entry->timer_index)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_expiration.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_expiration,
|
2018-11-26 08:47:31 -07:00
|
|
|
TP_PROTO(int vcpu_id, int timer_index, int direct, int msg_send_result),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index, direct, msg_send_result),
|
2015-12-23 06:54:00 -07:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
2018-11-26 08:47:31 -07:00
|
|
|
__field(int, direct)
|
2015-12-23 06:54:00 -07:00
|
|
|
__field(int, msg_send_result)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
2018-11-26 08:47:31 -07:00
|
|
|
__entry->direct = direct;
|
2015-12-23 06:54:00 -07:00
|
|
|
__entry->msg_send_result = msg_send_result;
|
|
|
|
|
),
|
|
|
|
|
|
2018-11-26 08:47:31 -07:00
|
|
|
TP_printk("vcpu_id %d timer %d direct %d send result %d",
|
2015-12-23 06:54:00 -07:00
|
|
|
__entry->vcpu_id, __entry->timer_index,
|
2018-11-26 08:47:31 -07:00
|
|
|
__entry->direct, __entry->msg_send_result)
|
2015-12-23 06:54:00 -07:00
|
|
|
);
|
|
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for stimer_cleanup.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_stimer_cleanup,
|
|
|
|
|
TP_PROTO(int vcpu_id, int timer_index),
|
|
|
|
|
TP_ARGS(vcpu_id, timer_index),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(int, vcpu_id)
|
|
|
|
|
__field(int, timer_index)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->timer_index = timer_index;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu_id %d timer %d",
|
|
|
|
|
__entry->vcpu_id, __entry->timer_index)
|
|
|
|
|
);
|
|
|
|
|
|
2016-05-04 13:09:48 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for AMD AVIC
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_avic_incomplete_ipi,
|
|
|
|
|
TP_PROTO(u32 vcpu, u32 icrh, u32 icrl, u32 id, u32 index),
|
|
|
|
|
TP_ARGS(vcpu, icrh, icrl, id, index),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u32, vcpu)
|
|
|
|
|
__field(u32, icrh)
|
|
|
|
|
__field(u32, icrl)
|
|
|
|
|
__field(u32, id)
|
|
|
|
|
__field(u32, index)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu = vcpu;
|
|
|
|
|
__entry->icrh = icrh;
|
|
|
|
|
__entry->icrl = icrl;
|
|
|
|
|
__entry->id = id;
|
|
|
|
|
__entry->index = index;
|
|
|
|
|
),
|
|
|
|
|
|
2019-09-05 20:17:20 -06:00
|
|
|
TP_printk("vcpu=%u, icrh:icrl=%#010x:%08x, id=%u, index=%u",
|
2016-05-04 13:09:48 -06:00
|
|
|
__entry->vcpu, __entry->icrh, __entry->icrl,
|
|
|
|
|
__entry->id, __entry->index)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_avic_unaccelerated_access,
|
|
|
|
|
TP_PROTO(u32 vcpu, u32 offset, bool ft, bool rw, u32 vec),
|
|
|
|
|
TP_ARGS(vcpu, offset, ft, rw, vec),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u32, vcpu)
|
|
|
|
|
__field(u32, offset)
|
|
|
|
|
__field(bool, ft)
|
|
|
|
|
__field(bool, rw)
|
|
|
|
|
__field(u32, vec)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu = vcpu;
|
|
|
|
|
__entry->offset = offset;
|
|
|
|
|
__entry->ft = ft;
|
|
|
|
|
__entry->rw = rw;
|
|
|
|
|
__entry->vec = vec;
|
|
|
|
|
),
|
|
|
|
|
|
2019-09-05 20:17:20 -06:00
|
|
|
TP_printk("vcpu=%u, offset=%#x(%s), %s, %s, vec=%#x",
|
2016-05-04 13:09:48 -06:00
|
|
|
__entry->vcpu,
|
|
|
|
|
__entry->offset,
|
|
|
|
|
__print_symbolic(__entry->offset, kvm_trace_symbol_apic),
|
|
|
|
|
__entry->ft ? "trap" : "fault",
|
|
|
|
|
__entry->rw ? "write" : "read",
|
|
|
|
|
__entry->vec)
|
|
|
|
|
);
|
|
|
|
|
|
2016-06-13 15:20:01 -06:00
|
|
|
TRACE_EVENT(kvm_hv_timer_state,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, unsigned int hv_timer_in_use),
|
|
|
|
|
TP_ARGS(vcpu_id, hv_timer_in_use),
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(unsigned int, vcpu_id)
|
|
|
|
|
__field(unsigned int, hv_timer_in_use)
|
|
|
|
|
),
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->hv_timer_in_use = hv_timer_in_use;
|
|
|
|
|
),
|
2019-06-13 19:15:48 -06:00
|
|
|
TP_printk("vcpu_id %x hv_timer %x",
|
2016-06-13 15:20:01 -06:00
|
|
|
__entry->vcpu_id,
|
|
|
|
|
__entry->hv_timer_in_use)
|
|
|
|
|
);
|
2018-05-16 09:21:29 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm_hv_flush_tlb.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_flush_tlb,
|
|
|
|
|
TP_PROTO(u64 processor_mask, u64 address_space, u64 flags),
|
|
|
|
|
TP_ARGS(processor_mask, address_space, flags),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u64, processor_mask)
|
|
|
|
|
__field(u64, address_space)
|
|
|
|
|
__field(u64, flags)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->processor_mask = processor_mask;
|
|
|
|
|
__entry->address_space = address_space;
|
|
|
|
|
__entry->flags = flags;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("processor_mask 0x%llx address_space 0x%llx flags 0x%llx",
|
|
|
|
|
__entry->processor_mask, __entry->address_space,
|
|
|
|
|
__entry->flags)
|
|
|
|
|
);
|
2018-05-16 09:21:30 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoint for kvm_hv_flush_tlb_ex.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_flush_tlb_ex,
|
|
|
|
|
TP_PROTO(u64 valid_bank_mask, u64 format, u64 address_space, u64 flags),
|
|
|
|
|
TP_ARGS(valid_bank_mask, format, address_space, flags),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u64, valid_bank_mask)
|
|
|
|
|
__field(u64, format)
|
|
|
|
|
__field(u64, address_space)
|
|
|
|
|
__field(u64, flags)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->valid_bank_mask = valid_bank_mask;
|
|
|
|
|
__entry->format = format;
|
|
|
|
|
__entry->address_space = address_space;
|
|
|
|
|
__entry->flags = flags;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("valid_bank_mask 0x%llx format 0x%llx "
|
|
|
|
|
"address_space 0x%llx flags 0x%llx",
|
|
|
|
|
__entry->valid_bank_mask, __entry->format,
|
|
|
|
|
__entry->address_space, __entry->flags)
|
|
|
|
|
);
|
2018-09-26 11:02:59 -06:00
|
|
|
|
|
|
|
|
/*
|
|
|
|
|
* Tracepoints for kvm_hv_send_ipi.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_hv_send_ipi,
|
|
|
|
|
TP_PROTO(u32 vector, u64 processor_mask),
|
|
|
|
|
TP_ARGS(vector, processor_mask),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u32, vector)
|
|
|
|
|
__field(u64, processor_mask)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vector = vector;
|
|
|
|
|
__entry->processor_mask = processor_mask;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vector %x processor_mask 0x%llx",
|
|
|
|
|
__entry->vector, __entry->processor_mask)
|
|
|
|
|
);
|
|
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_hv_send_ipi_ex,
|
|
|
|
|
TP_PROTO(u32 vector, u64 format, u64 valid_bank_mask),
|
|
|
|
|
TP_ARGS(vector, format, valid_bank_mask),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(u32, vector)
|
|
|
|
|
__field(u64, format)
|
|
|
|
|
__field(u64, valid_bank_mask)
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vector = vector;
|
|
|
|
|
__entry->format = format;
|
|
|
|
|
__entry->valid_bank_mask = valid_bank_mask;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vector %x format %llx valid_bank_mask 0x%llx",
|
|
|
|
|
__entry->vector, __entry->format,
|
|
|
|
|
__entry->valid_bank_mask)
|
|
|
|
|
);
|
2019-08-04 20:03:24 -06:00
|
|
|
|
|
|
|
|
TRACE_EVENT(kvm_pv_tlb_flush,
|
|
|
|
|
TP_PROTO(unsigned int vcpu_id, bool need_flush_tlb),
|
|
|
|
|
TP_ARGS(vcpu_id, need_flush_tlb),
|
|
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field( unsigned int, vcpu_id )
|
|
|
|
|
__field( bool, need_flush_tlb )
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->vcpu_id = vcpu_id;
|
|
|
|
|
__entry->need_flush_tlb = need_flush_tlb;
|
|
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_printk("vcpu %u need_flush_tlb %s", __entry->vcpu_id,
|
|
|
|
|
__entry->need_flush_tlb ? "true" : "false")
|
|
|
|
|
);
|
|
|
|
|
|
KVM: nVMX: add tracepoint for failed nested VM-Enter
Debugging a failed VM-Enter is often like searching for a needle in a
haystack, e.g. there are over 80 consistency checks that funnel into
the "invalid control field" error code. One way to expedite debug is
to run the buggy code as an L1 guest under KVM (and pray that the
failing check is detected by KVM). However, extracting useful debug
information out of L0 KVM requires attaching a debugger to KVM and/or
modifying the source, e.g. to log which check is failing.
Make life a little less painful for VMM developers and add a tracepoint
for failed VM-Enter consistency checks. Ideally the tracepoint would
capture both what check failed and precisely why it failed, but logging
why a checked failed is difficult to do in a generic tracepoint without
resorting to invasive techniques, e.g. generating a custom string on
failure. That being said, for the vast majority of VM-Enter failures
the most difficult step is figuring out exactly what to look at, e.g.
figuring out which bit was incorrectly set in a control field is usually
not too painful once the guilty field as been identified.
To reach a happy medium between precision and ease of use, simply log
the code that detected a failed check, using a macro to execute the
check and log the trace event on failure. This approach enables tracing
arbitrary code, e.g. it's not limited to function calls or specific
formats of checks, and the changes to the existing code are minimally
invasive. A macro with a two-character name is desirable as usage of
the macro doesn't result in overly long lines or confusing alignment,
while still retaining some amount of readability. I.e. a one-character
name is a little too terse, and a three-character name results in the
contents being passed to the macro aligning with an indented line when
the macro is used an in if-statement, e.g.:
if (VCC(nested_vmx_check_long_line_one(...) &&
nested_vmx_check_long_line_two(...)))
return -EINVAL;
And that is the story of how the CC(), a.k.a. Consistency Check, macro
got its name.
Signed-off-by: Sean Christopherson <sean.j.christopherson@intel.com>
Signed-off-by: Paolo Bonzini <pbonzini@redhat.com>
2019-07-11 09:58:29 -06:00
|
|
|
/*
|
|
|
|
|
* Tracepoint for failed nested VMX VM-Enter.
|
|
|
|
|
*/
|
|
|
|
|
TRACE_EVENT(kvm_nested_vmenter_failed,
|
2019-07-11 09:58:30 -06:00
|
|
|
TP_PROTO(const char *msg, u32 err),
|
|
|
|
|
TP_ARGS(msg, err),
|
KVM: nVMX: add tracepoint for failed nested VM-Enter
Debugging a failed VM-Enter is often like searching for a needle in a
haystack, e.g. there are over 80 consistency checks that funnel into
the "invalid control field" error code. One way to expedite debug is
to run the buggy code as an L1 guest under KVM (and pray that the
failing check is detected by KVM). However, extracting useful debug
information out of L0 KVM requires attaching a debugger to KVM and/or
modifying the source, e.g. to log which check is failing.
Make life a little less painful for VMM developers and add a tracepoint
for failed VM-Enter consistency checks. Ideally the tracepoint would
capture both what check failed and precisely why it failed, but logging
why a checked failed is difficult to do in a generic tracepoint without
resorting to invasive techniques, e.g. generating a custom string on
failure. That being said, for the vast majority of VM-Enter failures
the most difficult step is figuring out exactly what to look at, e.g.
figuring out which bit was incorrectly set in a control field is usually
not too painful once the guilty field as been identified.
To reach a happy medium between precision and ease of use, simply log
the code that detected a failed check, using a macro to execute the
check and log the trace event on failure. This approach enables tracing
arbitrary code, e.g. it's not limited to function calls or specific
formats of checks, and the changes to the existing code are minimally
invasive. A macro with a two-character name is desirable as usage of
the macro doesn't result in overly long lines or confusing alignment,
while still retaining some amount of readability. I.e. a one-character
name is a little too terse, and a three-character name results in the
contents being passed to the macro aligning with an indented line when
the macro is used an in if-statement, e.g.:
if (VCC(nested_vmx_check_long_line_one(...) &&
nested_vmx_check_long_line_two(...)))
return -EINVAL;
And that is the story of how the CC(), a.k.a. Consistency Check, macro
got its name.
Signed-off-by: Sean Christopherson <sean.j.christopherson@intel.com>
Signed-off-by: Paolo Bonzini <pbonzini@redhat.com>
2019-07-11 09:58:29 -06:00
|
|
|
|
|
|
|
|
TP_STRUCT__entry(
|
|
|
|
|
__field(const char *, msg)
|
2019-07-11 09:58:30 -06:00
|
|
|
__field(u32, err)
|
KVM: nVMX: add tracepoint for failed nested VM-Enter
Debugging a failed VM-Enter is often like searching for a needle in a
haystack, e.g. there are over 80 consistency checks that funnel into
the "invalid control field" error code. One way to expedite debug is
to run the buggy code as an L1 guest under KVM (and pray that the
failing check is detected by KVM). However, extracting useful debug
information out of L0 KVM requires attaching a debugger to KVM and/or
modifying the source, e.g. to log which check is failing.
Make life a little less painful for VMM developers and add a tracepoint
for failed VM-Enter consistency checks. Ideally the tracepoint would
capture both what check failed and precisely why it failed, but logging
why a checked failed is difficult to do in a generic tracepoint without
resorting to invasive techniques, e.g. generating a custom string on
failure. That being said, for the vast majority of VM-Enter failures
the most difficult step is figuring out exactly what to look at, e.g.
figuring out which bit was incorrectly set in a control field is usually
not too painful once the guilty field as been identified.
To reach a happy medium between precision and ease of use, simply log
the code that detected a failed check, using a macro to execute the
check and log the trace event on failure. This approach enables tracing
arbitrary code, e.g. it's not limited to function calls or specific
formats of checks, and the changes to the existing code are minimally
invasive. A macro with a two-character name is desirable as usage of
the macro doesn't result in overly long lines or confusing alignment,
while still retaining some amount of readability. I.e. a one-character
name is a little too terse, and a three-character name results in the
contents being passed to the macro aligning with an indented line when
the macro is used an in if-statement, e.g.:
if (VCC(nested_vmx_check_long_line_one(...) &&
nested_vmx_check_long_line_two(...)))
return -EINVAL;
And that is the story of how the CC(), a.k.a. Consistency Check, macro
got its name.
Signed-off-by: Sean Christopherson <sean.j.christopherson@intel.com>
Signed-off-by: Paolo Bonzini <pbonzini@redhat.com>
2019-07-11 09:58:29 -06:00
|
|
|
),
|
|
|
|
|
|
|
|
|
|
TP_fast_assign(
|
|
|
|
|
__entry->msg = msg;
|
2019-07-11 09:58:30 -06:00
|
|
|
__entry->err = err;
|
KVM: nVMX: add tracepoint for failed nested VM-Enter
Debugging a failed VM-Enter is often like searching for a needle in a
haystack, e.g. there are over 80 consistency checks that funnel into
the "invalid control field" error code. One way to expedite debug is
to run the buggy code as an L1 guest under KVM (and pray that the
failing check is detected by KVM). However, extracting useful debug
information out of L0 KVM requires attaching a debugger to KVM and/or
modifying the source, e.g. to log which check is failing.
Make life a little less painful for VMM developers and add a tracepoint
for failed VM-Enter consistency checks. Ideally the tracepoint would
capture both what check failed and precisely why it failed, but logging
why a checked failed is difficult to do in a generic tracepoint without
resorting to invasive techniques, e.g. generating a custom string on
failure. That being said, for the vast majority of VM-Enter failures
the most difficult step is figuring out exactly what to look at, e.g.
figuring out which bit was incorrectly set in a control field is usually
not too painful once the guilty field as been identified.
To reach a happy medium between precision and ease of use, simply log
the code that detected a failed check, using a macro to execute the
check and log the trace event on failure. This approach enables tracing
arbitrary code, e.g. it's not limited to function calls or specific
formats of checks, and the changes to the existing code are minimally
invasive. A macro with a two-character name is desirable as usage of
the macro doesn't result in overly long lines or confusing alignment,
while still retaining some amount of readability. I.e. a one-character
name is a little too terse, and a three-character name results in the
contents being passed to the macro aligning with an indented line when
the macro is used an in if-statement, e.g.:
if (VCC(nested_vmx_check_long_line_one(...) &&
nested_vmx_check_long_line_two(...)))
return -EINVAL;
And that is the story of how the CC(), a.k.a. Consistency Check, macro
got its name.
Signed-off-by: Sean Christopherson <sean.j.christopherson@intel.com>
Signed-off-by: Paolo Bonzini <pbonzini@redhat.com>
2019-07-11 09:58:29 -06:00
|
|
|
),
|
|
|
|
|
|
2019-07-11 09:58:30 -06:00
|
|
|
TP_printk("%s%s", __entry->msg, !__entry->err ? "" :
|
|
|
|
|
__print_symbolic(__entry->err, VMX_VMENTER_INSTRUCTION_ERRORS))
|
KVM: nVMX: add tracepoint for failed nested VM-Enter
Debugging a failed VM-Enter is often like searching for a needle in a
haystack, e.g. there are over 80 consistency checks that funnel into
the "invalid control field" error code. One way to expedite debug is
to run the buggy code as an L1 guest under KVM (and pray that the
failing check is detected by KVM). However, extracting useful debug
information out of L0 KVM requires attaching a debugger to KVM and/or
modifying the source, e.g. to log which check is failing.
Make life a little less painful for VMM developers and add a tracepoint
for failed VM-Enter consistency checks. Ideally the tracepoint would
capture both what check failed and precisely why it failed, but logging
why a checked failed is difficult to do in a generic tracepoint without
resorting to invasive techniques, e.g. generating a custom string on
failure. That being said, for the vast majority of VM-Enter failures
the most difficult step is figuring out exactly what to look at, e.g.
figuring out which bit was incorrectly set in a control field is usually
not too painful once the guilty field as been identified.
To reach a happy medium between precision and ease of use, simply log
the code that detected a failed check, using a macro to execute the
check and log the trace event on failure. This approach enables tracing
arbitrary code, e.g. it's not limited to function calls or specific
formats of checks, and the changes to the existing code are minimally
invasive. A macro with a two-character name is desirable as usage of
the macro doesn't result in overly long lines or confusing alignment,
while still retaining some amount of readability. I.e. a one-character
name is a little too terse, and a three-character name results in the
contents being passed to the macro aligning with an indented line when
the macro is used an in if-statement, e.g.:
if (VCC(nested_vmx_check_long_line_one(...) &&
nested_vmx_check_long_line_two(...)))
return -EINVAL;
And that is the story of how the CC(), a.k.a. Consistency Check, macro
got its name.
Signed-off-by: Sean Christopherson <sean.j.christopherson@intel.com>
Signed-off-by: Paolo Bonzini <pbonzini@redhat.com>
2019-07-11 09:58:29 -06:00
|
|
|
);
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
#endif /* _TRACE_KVM_H */
|
|
|
|
|
|
2010-03-10 04:00:43 -07:00
|
|
|
#undef TRACE_INCLUDE_PATH
|
2019-01-25 00:32:46 -07:00
|
|
|
#define TRACE_INCLUDE_PATH ../../arch/x86/kvm
|
2010-03-10 04:00:43 -07:00
|
|
|
#undef TRACE_INCLUDE_FILE
|
|
|
|
|
#define TRACE_INCLUDE_FILE trace
|
|
|
|
|
|
2009-06-17 06:22:14 -06:00
|
|
|
/* This part must be outside protection */
|
|
|
|
|
#include <trace/define_trace.h>
|