From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: X-Spam-Checker-Version: SpamAssassin 3.4.0 (2014-02-07) on aws-us-west-2-korg-lkml-1.web.codeaurora.org X-Spam-Level: X-Spam-Status: No, score=-17.4 required=3.0 tests=BAYES_00,DKIMWL_WL_HIGH, DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,HEADER_FROM_DIFFERENT_DOMAINS, INCLUDES_CR_TRAILER,INCLUDES_PATCH,MAILING_LIST_MULTI,NICE_REPLY_A, SPF_HELO_NONE,SPF_PASS,URIBL_BLOCKED,USER_AGENT_SANE_1 autolearn=unavailable autolearn_force=no version=3.4.0 Received: from mail.kernel.org (mail.kernel.org [198.145.29.99]) by smtp.lore.kernel.org (Postfix) with ESMTP id 1958CC433E6 for ; Thu, 21 Jan 2021 14:58:04 +0000 (UTC) Received: from vger.kernel.org (vger.kernel.org [23.128.96.18]) by mail.kernel.org (Postfix) with ESMTP id DD44F238E3 for ; Thu, 21 Jan 2021 14:58:03 +0000 (UTC) Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1732221AbhAUO5v (ORCPT ); Thu, 21 Jan 2021 09:57:51 -0500 Received: from us-smtp-delivery-124.mimecast.com ([216.205.24.124]:40018 "EHLO us-smtp-delivery-124.mimecast.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1732180AbhAUO4e (ORCPT ); Thu, 21 Jan 2021 09:56:34 -0500 DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=redhat.com; s=mimecast20190719; t=1611240908; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:cc:mime-version:mime-version:content-type:content-type: content-transfer-encoding:content-transfer-encoding: in-reply-to:in-reply-to:references:references; bh=A0y4gtYl6zPHb2XL96+ban/Fd6Uvd0EZ4hG18PlvPTk=; b=OXYC9z5UU+d1JwsRzZWPpFw3mSq2U3HMGSBZZzriM+Sx1KGX2jcO2EEABpH18mdR2gabF/ u36fXCA11q1O3MDzwxcVKDAL2g+tiwD6LGnqBGW9nHkv1n8oSWdfpt92x82M4R/plhVlc8 haslLq3PodEl6z+7jgKwOr6pTKi1NsM= Received: from mail-wm1-f69.google.com (mail-wm1-f69.google.com [209.85.128.69]) (Using TLS) by relay.mimecast.com with ESMTP id us-mta-365-kc0A2KzpMWusjXFAySsWAg-1; Thu, 21 Jan 2021 09:55:06 -0500 X-MC-Unique: kc0A2KzpMWusjXFAySsWAg-1 Received: by mail-wm1-f69.google.com with SMTP id z188so2548155wme.1 for ; Thu, 21 Jan 2021 06:55:05 -0800 (PST) X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:subject:to:cc:references:from:message-id:date :user-agent:mime-version:in-reply-to:content-language :content-transfer-encoding; bh=A0y4gtYl6zPHb2XL96+ban/Fd6Uvd0EZ4hG18PlvPTk=; b=MLiCa6zhhzJOWNcew5unlg8m1zYBmASTRlxMJv9LsBviNeb4IRghCf62b3cO3BqB7v GOYX1HBrckW2YOs9NigJyuKq69WfVZU5viSP9dcJTgFjtYBSfOipmlyZRrRQP2YJ81Ca 3xW4GB/4mVl3B6sQl9QcdWKcAtVC6bQlYgMz+BBjMQ574zLlrZLdIQaZKYEUhWjDgJiy MxLq1CxeXCsKa3IgX7ILZKrtJV0C7NA4ENNT4vNZ3AkgR1Hebqja+zIEVPxTAY5zpM17 OPY3zR6tjT2bqB7DavMvPGcXfPYeeDMKULDCbiaIzw1j4U4miqbsDT3AVVxeEHMInE8I b2UQ== X-Gm-Message-State: AOAM533B1IrLKjphjMxGJ5Ap/e3wPXeS80hs+TJgEUJO6EbLjWaZ4ZZz I5Z2hM24JXqVr3R+FrDTkWLkV6RbwsLGpwy+FQG54t28Kyn5PC4onB3Grl09KwTeiVSEG3IQVh6 Q+SntWkSC8Mv0J/aN6cq9DFad X-Received: by 2002:adf:e9d2:: with SMTP id l18mr14238869wrn.179.1611240904821; Thu, 21 Jan 2021 06:55:04 -0800 (PST) X-Google-Smtp-Source: ABdhPJwaEaoiAhi0IsSC+XU/owUQXtisdJWFBBv84U6kTN6VJbIS+xiC4rT7P0jvBCTLDJiD3AxJ9g== X-Received: by 2002:adf:e9d2:: with SMTP id l18mr14238848wrn.179.1611240904627; Thu, 21 Jan 2021 06:55:04 -0800 (PST) Received: from ?IPv6:2001:b07:6468:f312:5e2c:eb9a:a8b6:fd3e? ([2001:b07:6468:f312:5e2c:eb9a:a8b6:fd3e]) by smtp.gmail.com with ESMTPSA id b12sm2292471wrr.35.2021.01.21.06.55.03 (version=TLS1_3 cipher=TLS_AES_128_GCM_SHA256 bits=128/128); Thu, 21 Jan 2021 06:55:03 -0800 (PST) Subject: Re: [PATCH] kvm: tracing: Fix unmatched kvm_entry and kvm_exit events To: Dario Faggioli , linux-kernel@vger.kernel.org Cc: Lorenzo Brescia , Sean Christopherson , Vitaly Kuznetsov , Wanpeng Li , Jim Mattson , Joerg Roedel , kvm@vger.kernel.org References: <160873470698.11652.13483635328769030605.stgit@Wayrath> From: Paolo Bonzini Message-ID: Date: Thu, 21 Jan 2021 15:55:02 +0100 User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:78.0) Gecko/20100101 Thunderbird/78.6.0 MIME-Version: 1.0 In-Reply-To: <160873470698.11652.13483635328769030605.stgit@Wayrath> Content-Type: text/plain; charset=utf-8; format=flowed Content-Language: en-US Content-Transfer-Encoding: 7bit Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On 23/12/20 15:45, Dario Faggioli wrote: > From: Lorenzo Brescia > > On VMX, if we exit and then re-enter immediately without leaving > the vmx_vcpu_run() function, the kvm_entry event is not logged. > That means we will see one (or more) kvm_exit, without its (their) > corresponding kvm_entry, as shown here: > > CPU-1979 [002] 89.871187: kvm_entry: vcpu 1 > CPU-1979 [002] 89.871218: kvm_exit: reason MSR_WRITE > CPU-1979 [002] 89.871259: kvm_exit: reason MSR_WRITE > > It also seems possible for a kvm_entry event to be logged, but then > we leave vmx_vcpu_run() right away (if vmx->emulation_required is > true). In this case, we will have a spurious kvm_entry event in the > trace. > > Fix these situations by moving trace_kvm_entry() inside vmx_vcpu_run() > (where trace_kvm_exit() already is). > > A trace obtained with this patch applied looks like this: > > CPU-14295 [000] 8388.395387: kvm_entry: vcpu 0 > CPU-14295 [000] 8388.395392: kvm_exit: reason MSR_WRITE > CPU-14295 [000] 8388.395393: kvm_entry: vcpu 0 > CPU-14295 [000] 8388.395503: kvm_exit: reason EXTERNAL_INTERRUPT > > Of course, not calling trace_kvm_entry() in common x86 code any > longer means that we need to adjust the SVM side of things too. > > Signed-off-by: Lorenzo Brescia > Signed-off-by: Dario Faggioli > --- > Cc: Paolo Bonzini > Cc: Sean Christopherson > Cc: Vitaly Kuznetsov > Cc: Wanpeng Li > Cc: Jim Mattson > Cc: Joerg Roedel > Cc: kvm@vger.kernel.org > Cc: Lorenzo Brescia > Cc: Dario Faggioli > --- > arch/x86/kvm/svm/svm.c | 2 ++ > arch/x86/kvm/vmx/vmx.c | 2 ++ > arch/x86/kvm/x86.c | 3 +-- > 3 files changed, 5 insertions(+), 2 deletions(-) > > diff --git a/arch/x86/kvm/svm/svm.c b/arch/x86/kvm/svm/svm.c > index cce0143a6f80..ed272fcf6495 100644 > --- a/arch/x86/kvm/svm/svm.c > +++ b/arch/x86/kvm/svm/svm.c > @@ -3741,6 +3741,8 @@ static __no_kcsan fastpath_t svm_vcpu_run(struct kvm_vcpu *vcpu) > { > struct vcpu_svm *svm = to_svm(vcpu); > > + trace_kvm_entry(vcpu); > + > svm->vmcb->save.rax = vcpu->arch.regs[VCPU_REGS_RAX]; > svm->vmcb->save.rsp = vcpu->arch.regs[VCPU_REGS_RSP]; > svm->vmcb->save.rip = vcpu->arch.regs[VCPU_REGS_RIP]; > diff --git a/arch/x86/kvm/vmx/vmx.c b/arch/x86/kvm/vmx/vmx.c > index 75c9c6a0a3a4..ff20f9e6e5b3 100644 > --- a/arch/x86/kvm/vmx/vmx.c > +++ b/arch/x86/kvm/vmx/vmx.c > @@ -6653,6 +6653,8 @@ static fastpath_t vmx_vcpu_run(struct kvm_vcpu *vcpu) > if (vmx->emulation_required) > return EXIT_FASTPATH_NONE; > > + trace_kvm_entry(vcpu); > + > if (vmx->ple_window_dirty) { > vmx->ple_window_dirty = false; > vmcs_write32(PLE_WINDOW, vmx->ple_window); > diff --git a/arch/x86/kvm/x86.c b/arch/x86/kvm/x86.c > index 3f7c1fc7a3ce..a79666204907 100644 > --- a/arch/x86/kvm/x86.c > +++ b/arch/x86/kvm/x86.c > @@ -8973,8 +8973,6 @@ static int vcpu_enter_guest(struct kvm_vcpu *vcpu) > kvm_x86_ops.request_immediate_exit(vcpu); > } > > - trace_kvm_entry(vcpu); > - > fpregs_assert_state_consistent(); > if (test_thread_flag(TIF_NEED_FPU_LOAD)) > switch_fpu_return(); > @@ -11538,6 +11536,7 @@ int kvm_sev_es_string_io(struct kvm_vcpu *vcpu, unsigned int size, > } > EXPORT_SYMBOL_GPL(kvm_sev_es_string_io); > > +EXPORT_TRACEPOINT_SYMBOL_GPL(kvm_entry); > EXPORT_TRACEPOINT_SYMBOL_GPL(kvm_exit); > EXPORT_TRACEPOINT_SYMBOL_GPL(kvm_fast_mmio); > EXPORT_TRACEPOINT_SYMBOL_GPL(kvm_inj_virq); > > Queued, thanks. Paolo