| lists.openwall.net | lists / announce owl-users owl-dev john-users john-dev passwdqc-users yescrypt popa3d-users / oss-security kernel-hardening musl sabotage tlsify passwords / crypt-dev xvendor / Bugtraq Full-Disclosure linux-kernel linux-netdev linux-ext4 linux-hardening linux-cve-announce PHC | |
|
Open Source and information security mailing list archives
| ||
|
Message-ID: <20210113051857.GF7528@xsang-OptiPlex-9020>
Date: Wed, 13 Jan 2021 13:18:57 +0800
From: kernel test robot <oliver.sang@...el.com>
To: Maxim Levitsky <mlevitsk@...hat.com>
Cc: Paolo Bonzini <pbonzini@...hat.com>,
LKML <linux-kernel@...r.kernel.org>, lkp@...ts.01.org,
lkp@...el.com
Subject: [KVM] f2c7ef3ba9: WARNING:at_arch/x86/kvm/x86.c:#vcpu_enter_guest
Greeting,
FYI, we noticed the following commit (built with gcc-9):
commit: f2c7ef3ba9556d62a7e2bb23b563c6510007d55c ("KVM: nSVM: cancel KVM_REQ_GET_NESTED_STATE_PAGES on nested vmexit")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: kernel-selftests
version: kernel-selftests-x86_64-cb0debfe-1_20201231
with following parameters:
group: kvm
ucode: 0xe2
test-description: The kernel contains a set of "self tests" under the tools/testing/selftests/ directory. These are intended to be small unit tests to exercise individual code paths in the kernel.
test-url: https://www.kernel.org/doc/Documentation/kselftest.txt
on test machine: 8 threads Intel(R) Core(TM) i7-6700 CPU @ 3.40GHz with 28G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang@...el.com>
[ 80.470988] WARNING: CPU: 2 PID: 10215 at arch/x86/kvm/x86.c:8792 vcpu_enter_guest (kbuild/src/consumer/arch/x86/kvm/x86.c:8792 (discriminator 1))
[ 80.481219] Modules linked in: btrfs blake2b_generic xor zstd_compress raid6_pq libcrc32c ipmi_devintf ipmi_msghandler sd_mod t10_pi snd_hda_codec_hdmi sg intel_rapl_msr intel_rapl_common snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio snd_hda_intel snd_intel_dspcfg snd_hda_codec x86_pkg_temp_thermal intel_powerclamp snd_hda_core coretemp crct10dif_pclmul crc32_pclmul crc32c_intel dell_wmi snd_hwdep ghash_clmulni_intel aesni_intel ahci crypto_simd dell_smbios snd_pcm libahci cryptd i915 mei_wdt wmi_bmof dell_wmi_descriptor sparse_keymap dcdbas glue_helper snd_timer mei_me joydev libata i2c_i801 snd mei i2c_smbus pcspkr soundcore wmi video intel_pmc_core acpi_pad ip_tables
[ 80.550337] CPU: 2 PID: 10215 Comm: evmcs_test Tainted: G I 5.10.0-00225-gf2c7ef3ba955 #1
[ 80.561728] Hardware name: Dell Inc. OptiPlex 7040/0Y7WYT, BIOS 1.2.8 01/26/2016
[ 80.571170] RIP: 0010:vcpu_enter_guest (kbuild/src/consumer/arch/x86/kvm/x86.c:8792 (discriminator 1))
[ 80.577859] Code: 8b 84 24 40 03 00 00 a9 00 00 01 00 74 23 49 8b b4 24 38 03 00 00 ba 01 00 00 00 bf 04 00 00 00 e8 8f 47 1a 00 e9 94 fa ff ff <0f> 0b e9 db f0 ff ff 48 8b 05 64 55 2b 02 be 10 00 00 00 4c 89 e7
All code
========
0: 8b 84 24 40 03 00 00 mov 0x340(%rsp),%eax
7: a9 00 00 01 00 test $0x10000,%eax
c: 74 23 je 0x31
e: 49 8b b4 24 38 03 00 mov 0x338(%r12),%rsi
15: 00
16: ba 01 00 00 00 mov $0x1,%edx
1b: bf 04 00 00 00 mov $0x4,%edi
20: e8 8f 47 1a 00 callq 0x1a47b4
25: e9 94 fa ff ff jmpq 0xfffffffffffffabe
2a:* 0f 0b ud2 <-- trapping instruction
2c: e9 db f0 ff ff jmpq 0xfffffffffffff10c
31: 48 8b 05 64 55 2b 02 mov 0x22b5564(%rip),%rax # 0x22b559c
38: be 10 00 00 00 mov $0x10,%esi
3d: 4c 89 e7 mov %r12,%rdi
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: e9 db f0 ff ff jmpq 0xfffffffffffff0e2
7: 48 8b 05 64 55 2b 02 mov 0x22b5564(%rip),%rax # 0x22b5572
e: be 10 00 00 00 mov $0x10,%esi
13: 4c 89 e7 mov %r12,%rdi
[ 80.600097] RSP: 0018:ffffc9000f65bd90 EFLAGS: 00010246
[ 80.606925] RAX: 0000000103214101 RBX: ffff88874c204000 RCX: 0000000000000000
[ 80.616094] RDX: ffff88874c204000 RSI: ffffffff81051b28 RDI: ffff88874c6cb100
[ 80.624814] RBP: ffffc9000f65be20 R08: 0000000000000028 R09: 0000000000020602
[ 80.633533] R10: ffffc9000f65be40 R11: 0000000000000000 R12: ffff88874c6cb100
[ 80.642302] R13: 0000000000000000 R14: ffffc9000f91edd8 R15: ffff88874c603180
[ 80.651200] FS: 00007eff2deb4740(0000) GS:ffff888759cc0000(0000) knlGS:0000000000000000
[ 80.660831] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 80.668245] CR2: 00007eff2e09e008 CR3: 000000074c63e004 CR4: 00000000003726e0
[ 80.678526] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 80.688799] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 80.699047] Call Trace:
[ 80.703095] ? kvm_arch_vcpu_ioctl_run (kbuild/src/consumer/arch/x86/kvm/x86.c:9133 kbuild/src/consumer/arch/x86/kvm/x86.c:9360)
[ 80.709525] ? kvm_arch_vcpu_ioctl_run (kbuild/src/consumer/arch/x86/kvm/x86.c:9128 kbuild/src/consumer/arch/x86/kvm/x86.c:9360)
[ 80.715798] kvm_arch_vcpu_ioctl_run (kbuild/src/consumer/arch/x86/kvm/x86.c:9133 kbuild/src/consumer/arch/x86/kvm/x86.c:9360)
[ 80.721920] kvm_vcpu_ioctl (kbuild/src/consumer/arch/x86/kvm/../../../virt/kvm/kvm_main.c:3282)
[ 80.727376] __x64_sys_ioctl (kbuild/src/consumer/fs/ioctl.c:49 kbuild/src/consumer/fs/ioctl.c:753 kbuild/src/consumer/fs/ioctl.c:739 kbuild/src/consumer/fs/ioctl.c:739)
[ 80.732653] do_syscall_64 (kbuild/src/consumer/arch/x86/entry/common.c:46)
[ 80.737680] entry_SYSCALL_64_after_hwframe (kbuild/src/consumer/arch/x86/entry/entry_64.S:127)
[ 80.744223] RIP: 0033:0x7eff2dfa7427
[ 80.749073] Code: 00 00 90 48 8b 05 69 aa 0c 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 39 aa 0c 00 f7 d8 64 89 01 48
All code
========
0: 00 00 add %al,(%rax)
2: 90 nop
3: 48 8b 05 69 aa 0c 00 mov 0xcaa69(%rip),%rax # 0xcaa73
a: 64 c7 00 26 00 00 00 movl $0x26,%fs:(%rax)
11: 48 c7 c0 ff ff ff ff mov $0xffffffffffffffff,%rax
18: c3 retq
19: 66 2e 0f 1f 84 00 00 nopw %cs:0x0(%rax,%rax,1)
20: 00 00 00
23: b8 10 00 00 00 mov $0x10,%eax
28: 0f 05 syscall
2a:* 48 3d 01 f0 ff ff cmp $0xfffffffffffff001,%rax <-- trapping instruction
30: 73 01 jae 0x33
32: c3 retq
33: 48 8b 0d 39 aa 0c 00 mov 0xcaa39(%rip),%rcx # 0xcaa73
3a: f7 d8 neg %eax
3c: 64 89 01 mov %eax,%fs:(%rcx)
3f: 48 rex.W
Code starting with the faulting instruction
===========================================
0: 48 3d 01 f0 ff ff cmp $0xfffffffffffff001,%rax
6: 73 01 jae 0x9
8: c3 retq
9: 48 8b 0d 39 aa 0c 00 mov 0xcaa39(%rip),%rcx # 0xcaa49
10: f7 d8 neg %eax
12: 64 89 01 mov %eax,%fs:(%rcx)
15: 48 rex.W
[ 80.770577] RSP: 002b:00007ffe2f2fc2f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 80.779494] RAX: ffffffffffffffda RBX: 000000000112e710 RCX: 00007eff2dfa7427
[ 80.787915] RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000009
[ 80.796290] RBP: 000000000000000a R08: 000000000041054f R09: 0000000000000005
[ 80.804634] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000005
[ 80.813097] R13: 000000000112d260 R14: 00007ffe2f2fc410 R15: 000000000000000a
[ 80.821511] irq event stamp: 61483
[ 80.826199] hardirqs last enabled at (61509): vmx_vcpu_load_vmcs (kbuild/src/consumer/arch/x86/include/asm/paravirt.h:663 kbuild/src/consumer/arch/x86/kvm/vmx/vmx.c:1345)
[ 80.836414] hardirqs last disabled at (61530): __schedule (kbuild/src/consumer/kernel/sched/core.c:4428 (discriminator 1))
[ 80.846056] softirqs last enabled at (61548): __do_softirq (kbuild/src/consumer/arch/x86/include/asm/preempt.h:26 kbuild/src/consumer/kernel/softirq.c:326)
[ 80.855684] softirqs last disabled at (61561): asm_call_irq_on_stack (kbuild/src/consumer/arch/x86/entry/entry_64.S:796)
[ 80.866087] ---[ end trace 17d85e5c805ecff5 ]---
[ 81.330924] APIC base relocation is unsupported by KVM
[ 86.036122] set_tsc_khz: 49 callbacks suppressed
[ 86.036123] user requested TSC rate below hardware speed
[ 89.546152] user requested TSC rate below hardware speed
[ 91.763702] gcc -Wall -Wstrict-prototypes -Wuninitialized -O2 -g -std=gnu99 -fno-stack-protector -fno-PIE -I../../../../tools/include -I../../../../tools/arch/x86/include -I../../../../usr/include/ -Iinclude -Ix86_64 -Iinclude/x86_64 -I.. -pthread -no-pie x86_64/cr4_cpuid_sync_test.c /usr/src/perf_selftests-x86_64-rhel-7.6-kselftests-f2c7ef3ba9556d62a7e2bb23b563c6510007d55c/tools/testing/selftests/kvm/libkvm.a -o /usr/src/perf_selftests-x86_64-rhel-7.6-kselftests-f2c7ef3ba9556d62a7e2bb23b563c6510007d55c/tools/testing/selftests/kvm/x86_64/cr4_cpuid_sync_test
[ 91.763708]
[ 93.431239] user requested TSC rate below hardware speed
[ 95.433636] user requested TSC rate below hardware speed
[ 97.904112] user requested TSC rate below hardware speed
[ 97.912454] user requested TSC rate below hardware speed
[ 97.918900] user requested TSC rate below hardware speed
[ 97.925453] user requested TSC rate below hardware speed
[ 97.931952] user requested TSC rate below hardware speed
[ 97.938499] user requested TSC rate below hardware speed
[ 97.945171] user requested TSC rate below hardware speed
[ 97.951686] user requested TSC rate below hardware speed
[ 98.789612] set_tsc_khz: 570 callbacks suppressed
[ 98.789614] user requested TSC rate below hardware speed
[ 99.205799] user requested TSC rate below hardware speed
[ 99.639430] user requested TSC rate below hardware speed
[ 100.071547] user requested TSC rate below hardware speed
[ 100.524120] user requested TSC rate below hardware speed
[ 100.948135] user requested TSC rate below hardware speed
[ 101.397471] user requested TSC rate below hardware speed
[ 101.832249] user requested TSC rate below hardware speed
[ 102.259108] user requested TSC rate below hardware speed
[ 102.697270] user requested TSC rate below hardware speed
[ 103.993850] set_tsc_khz: 2 callbacks suppressed
[ 103.993852] user requested TSC rate below hardware speed
[ 104.427281] user requested TSC rate below hardware speed
[ 104.863518] user requested TSC rate below hardware speed
[ 105.310148] user requested TSC rate below hardware speed
[ 105.732255] user requested TSC rate below hardware speed
[ 105.855542] gcc -Wall -Wstrict-prototypes -Wuninitialized -O2 -g -std=gnu99 -fno-stack-protector -fno-PIE -I../../../../tools/include -I../../../../tools/arch/x86/include -I../../../../usr/include/ -Iinclude -Ix86_64 -Iinclude/x86_64 -I.. -pthread -no-pie x86_64/evmcs_test.c /usr/src/perf_selftests-x86_64-rhel-7.6-kselftests-f2c7ef3ba9556d62a7e2bb23b563c6510007d55c/tools/testing/selftests/kvm/libkvm.a -o /usr/src/perf_selftests-x86_64-rhel-7.6-kselftests-f2c7ef3ba9556d62a7e2bb23b563c6510007d55c/tools/testing/selftests/kvm/x86_64/evmcs_test
[ 105.855547]
[ 106.165256] user requested TSC rate below hardware speed
[ 106.603187] user requested TSC rate below hardware speed
[ 107.066262] user requested TSC rate below hardware speed
[ 107.091703] user requested TSC rate below hardware speed
[ 107.097905] user requested TSC rate below hardware speed
[ 116.572500] watchdog: watchdog0: watchdog did not stop!
[ 116.635757] kvm: exiting hardware virtualization
[ 116.641438] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
[ 116.647247] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[ 116.655179] e1000e: EEE TX LPI TIMER: 00000011
reboot: Restarting system
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Oliver Sang
View attachment "config-5.10.0-00225-gf2c7ef3ba955" of type "text/plain" (210804 bytes)
View attachment "job-script" of type "text/plain" (5959 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (35456 bytes)
View attachment "kernel-selftests" of type "text/plain" (72897 bytes)
View attachment "job.yaml" of type "text/plain" (5032 bytes)
View attachment "reproduce" of type "text/plain" (134 bytes)
Powered by blists - more mailing lists