Bug 195315 - Kernel WARNING: At arch/x86/kvm/lapic.c:1468 kvm_lapic_expired_hv_timer+0xee/0x110 [kvm]
Summary: Kernel WARNING: At arch/x86/kvm/lapic.c:1468 kvm_lapic_expired_hv_timer+0xee/...
Status: NEW
Alias: None
Product: Virtualization
Classification: Unclassified
Component: kvm (show other bugs)
Hardware: x86-64 Linux
: P1 normal
Assignee: virtualization_kvm
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2017-04-10 19:07 UTC by Paul
Modified: 2017-06-12 15:23 UTC (History)
3 users (show)

See Also:
Kernel Version: 4.10.10
Subsystem:
Regression: No
Bisected commit-id:


Attachments

Description Paul 2017-04-10 19:07:29 UTC
I've been running Windows 7 (64bit) as a guest with QEMU(git-master)/KVM and it seems that since I've swicthed to 4.10 (To be honest, I am not sure if 4.9 also had these warnings), it started to give me the following warning:

[  +0.000029] WARNING: CPU: 2 PID: 23992 at arch/x86/kvm/lapic.c:1468 kvm_lapic_expired_hv_timer+0xee/0x110 [kvm]
[  +0.000002] Modules linked in: vhost_net vhost macvtap macvlan fuse ctr ccm xt_CHECKSUM iptable_mangle ipt_MASQUERADE nf_nat_masquerade_ipv4 iptable_nat nf_nat_ipv4 nf_nat libcrc32c crc32c_generic nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack ipt_REJECT nf_reject_ipv4 xt_tcpudp tun bridge stp llc ebtable_filter ebtables ip6table_filter ip6_tables iptable_filter snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic arc4 nls_iso8859_1 nls_cp437 vfat fat intel_rapl iwlmvm x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel eeepc_wmi mac80211 asus_wmi iTCO_wdt kvm iTCO_vendor_support sparse_keymap irqbypass nouveau snd_hda_intel crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel mousedev iwlwifi snd_hda_codec evdev input_leds joydev aesni_intel mac_hid snd_hwdep
[  +0.000064]  aes_x86_64 crypto_simd snd_hda_core cryptd led_class mxm_wmi glue_helper cfg80211 snd_pcm ttm e1000e intel_cstate i2c_algo_bit snd_timer intel_rapl_perf snd soundcore psmouse mei_me ptp hci_uart pps_core mei i2c_i801 pcspkr shpchp btbcm btqca btintel bluetooth battery rfkill wmi video i2c_hid intel_lpss_acpi intel_lpss acpi_pad button acpi_als tpm_tis kfifo_buf tpm_tis_core industrialio tpm fjes sch_fq_codel sg ip_tables x_tables ext4 crc16 jbd2 fscrypto mbcache hid_generic usbhid hid sd_mod serio_raw atkbd libps2 ahci libahci xhci_pci libata xhci_hcd scsi_mod usbcore usb_common i8042 serio nvidia_drm(PO) drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm nvidia_uvm(PO) nvidia_modeset(PO) nvidia(PO)
[  +0.000078] CPU: 2 PID: 23992 Comm: CPU 0/KVM Tainted: P           O    4.10.9-1-ck #1
[  +0.000002] Hardware name: System manufacturer System Product Name/Z170-A, BIOS 3401 01/25/2017
[  +0.000001] Call Trace:
[  +0.000009]  dump_stack+0x76/0xa0
[  +0.000005]  __warn+0xda/0x100
[  +0.000004]  warn_slowpath_null+0x30/0x40
[  +0.000020]  kvm_lapic_expired_hv_timer+0xee/0x110 [kvm]
[  +0.000006]  handle_preemption_timer+0x21/0x30 [kvm_intel]
[  +0.000006]  vmx_handle_exit+0x169/0x1480 [kvm_intel]
[  +0.000005]  ? clear_atomic_switch_msr+0x15a/0x180 [kvm_intel]
[  +0.000005]  ? atomic_switch_perf_msrs+0x7e/0xb0 [kvm_intel]
[  +0.000021]  kvm_arch_vcpu_ioctl_run+0x880/0x1690 [kvm]
[  +0.000005]  ? _copy_to_user+0x67/0x80
[  +0.000013]  kvm_vcpu_ioctl+0x348/0x640 [kvm]
[  +0.000004]  do_vfs_ioctl+0xb2/0x600
[  +0.000005]  ? __fget+0x8a/0xc0
[  +0.000002]  SyS_ioctl+0x88/0xa0
[  +0.000006]  entry_SYSCALL_64_fastpath+0x1a/0xa9
[  +0.000002] RIP: 0033:0x7f57e371d0d7
[  +0.000003] RSP: 002b:00007f57d52fb8a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[  +0.000003] RAX: ffffffffffffffda RBX: 000000000000ae80 RCX: 00007f57e371d0d7
[  +0.000002] RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000012
[  +0.000001] RBP: 00007f57d5b4fc00 R08: 000055e6c3098b90 R09: 00000000000000ff
[  +0.000002] R10: 0000000000000001 R11: 0000000000000246 R12: 0000000000000000
[  +0.000001] R13: 00007f57ea988000 R14: 0000000000000000 R15: 00007f57d5b4fc00
[  +0.000003] ---[ end trace b3c01e167a695ca4 ]---
Comment 1 Martin Schmidt 2017-05-30 21:28:43 UTC
Same here.


May 30 13:15:32 rough kernel: ------------[ cut here ]------------
May 30 13:15:32 rough kernel: WARNING: CPU: 0 PID: 1052 at arch/x86/kvm/lapic.c:1529 kvm_lapic_expired_hv_timer+0xd2/0xf0 [kvm]
May 30 13:15:32 rough kernel: Modules linked in: fuse rfcomm ctr ccm bnep snd_hda_codec_hdmi hid_multitouch dell_led arc4 snd_hda_codec_realtek snd_hda_codec_generic iTCO_wdt i
May 30 13:15:32 rough kernel:  uvcvideo drm_kms_helper videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core drm videodev media shpchp intel_gtt mei_me joydev syscop
May 30 13:15:32 rough kernel:  rtsx_pci xhci_pci libata xhci_hcd scsi_mod usbcore usb_common i8042 serio nvme nvme_core
May 30 13:15:32 rough kernel: CPU: 0 PID: 1052 Comm: qemu-system-x86 Not tainted 4.11.3-1-ARCH #1
May 30 13:15:32 rough kernel: Hardware name: Dell Inc. XPS 13 9350/09JHRY, BIOS 1.4.13 12/28/2016
May 30 13:15:32 rough kernel: Call Trace:
May 30 13:15:32 rough kernel:  dump_stack+0x63/0x81
May 30 13:15:32 rough kernel:  __warn+0xcb/0xf0
May 30 13:15:32 rough kernel:  warn_slowpath_null+0x1d/0x20
May 30 13:15:32 rough kernel:  kvm_lapic_expired_hv_timer+0xd2/0xf0 [kvm]
May 30 13:15:32 rough kernel:  handle_preemption_timer+0xe/0x20 [kvm_intel]
May 30 13:15:32 rough kernel:  vmx_handle_exit+0xba/0x1460 [kvm_intel]
May 30 13:15:32 rough kernel:  ? atomic_switch_perf_msrs+0x6f/0xa0 [kvm_intel]
May 30 13:15:32 rough kernel:  ? vmx_vcpu_run+0x322/0x430 [kvm_intel]
May 30 13:15:32 rough kernel:  kvm_arch_vcpu_ioctl_run+0xd09/0x1730 [kvm]
May 30 13:15:32 rough kernel:  ? kvm_arch_vcpu_load+0x6d/0x290 [kvm]
May 30 13:15:32 rough kernel:  ? __vmx_load_host_state.part.30+0x128/0x210 [kvm_intel]
May 30 13:15:32 rough kernel:  kvm_vcpu_ioctl+0x2a6/0x640 [kvm]
May 30 13:15:32 rough kernel:  ? kvm_vcpu_ioctl+0x2a6/0x640 [kvm]
May 30 13:15:32 rough kernel:  ? __vfs_write+0x37/0x140
May 30 13:15:32 rough kernel:  do_vfs_ioctl+0xa5/0x600
May 30 13:15:32 rough kernel:  ? __fget+0x77/0xb0
May 30 13:15:32 rough kernel:  SyS_ioctl+0x79/0x90
May 30 13:15:32 rough kernel:  entry_SYSCALL_64_fastpath+0x1a/0xa9
May 30 13:15:32 rough kernel: RIP: 0033:0x7f7361932cb7
May 30 13:15:32 rough kernel: RSP: 002b:00007f73539fe8e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
May 30 13:15:32 rough kernel: RAX: ffffffffffffffda RBX: 000000000000ae80 RCX: 00007f7361932cb7
May 30 13:15:32 rough kernel: RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000013
May 30 13:15:32 rough kernel: RBP: 00007f73565c3380 R08: 00005644f48f3030 R09: 00000000ffffffff
May 30 13:15:32 rough kernel: R10: 001fc2e06eb0e15d R11: 0000000000000246 R12: 0000000000000000
May 30 13:15:32 rough kernel: R13: 00007f7368987000 R14: 0000000000000000 R15: 00007f73565c3380
May 30 13:15:32 rough kernel: ---[ end trace efc86909ac59bec5 ]---
Comment 2 Gustavo Alvarez Lopez 2017-06-09 16:48:27 UTC
same here

[140852.892629] ------------[ cut here ]------------
[140852.892661] WARNING: CPU: 23 PID: 25829 at arch/x86/kvm/lapic.c:1529 kvm_lapic_expired_hv_timer+0xd2/0xf0 [kvm]
[140852.892662] Modules linked in: vhost_net vhost tap rfcomm ipt_REJECT nf_reject_ipv4 vhba(O) xt_multiport ebtable_filter ebtables ip6table_filter ip6_tables tun mpt3sas raid_class scsi_transport_sas mptctl mptbase bnep ipt_MASQUERADE nf_nat_masquerade_ipv4 iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack cpufreq_ondemand libcrc32c crc32c_generic msr iptable_filter f71882fg snd_hda_codec_hdmi intel_powerclamp coretemp kvm_intel kvm crct10dif_pclmul crc32_pclmul i2c_dev crc32c_intel ghash_clmulni_intel iTCO_wdt gpio_ich iTCO_vendor_support ppdev pcbc mxm_wmi nvidia_drm(PO) nvidia_modeset(PO) nvidia(PO) aesni_intel snd_hda_codec_realtek aes_x86_64 snd_hda_codec_generic crypto_simd glue_helper cryptd input_leds led_class intel_cstate evdev mousedev snd_usb_audio mac_hid btusb
[140852.892707]  snd_usbmidi_lib pcspkr psmouse snd_hda_intel btrtl drm_kms_helper btbcm snd_rawmidi btintel i2c_i801 snd_hda_codec snd_seq_device drm bluetooth snd_hda_core i7core_edac snd_hwdep syscopyarea snd_pcm sysfillrect snd_timer snd sysimgblt rfkill fb_sys_fops soundcore lpc_ich sky2 i5500_temp edac_core shpchp parport_pc parport wmi button tpm_tis tpm_tis_core tpm sch_fq_codel uinput br_netfilter bridge stp llc fuse eeprom sg acpi_cpufreq ip_tables x_tables ext4 crc16 jbd2 fscrypto mbcache ata_generic sr_mod pata_acpi cdrom sd_mod hid_generic usbhid hid serio_raw atkbd libps2 uhci_hcd ahci pata_marvell libahci xhci_pci ehci_pci xhci_hcd libata megaraid_sas ehci_hcd usbcore i8042 usb_common scsi_mod serio vfio_pci irqbypass vfio_virqfd vfio_iommu_type1 vfio
[140852.892760] CPU: 23 PID: 25829 Comm: CPU 5/KVM Tainted: P        W  O    4.11.3-1-ARCH #1
[140852.892761] Hardware name: To Be Filled By O.E.M. To Be Filled By O.E.M./EVGA Classified SR-2, BIOS 080016  08/25/2011
[140852.892762] Call Trace:
[140852.892770]  dump_stack+0x63/0x81
[140852.892774]  __warn+0xcb/0xf0
[140852.892777]  warn_slowpath_null+0x1d/0x20
[140852.892787]  kvm_lapic_expired_hv_timer+0xd2/0xf0 [kvm]
[140852.892792]  handle_preemption_timer+0xe/0x20 [kvm_intel]
[140852.892800]  vmx_handle_exit+0xba/0x1460 [kvm_intel]
[140852.892803]  ? atomic_switch_perf_msrs+0x6f/0xa0 [kvm_intel]
[140852.892806]  ? vmx_vcpu_run+0x322/0x430 [kvm_intel]
[140852.892816]  kvm_arch_vcpu_ioctl_run+0xd09/0x1730 [kvm]
[140852.892826]  kvm_vcpu_ioctl+0x2a6/0x640 [kvm]
[140852.892834]  ? kvm_vcpu_ioctl+0x2a6/0x640 [kvm]
[140852.892837]  ? vfio_pci_rw+0x37/0x90 [vfio_pci]
[140852.892839]  ? vfio_pci_read+0x19/0x20 [vfio_pci]
[140852.892841]  ? vfio_device_fops_read+0x24/0x30 [vfio]
[140852.892845]  ? __vfs_read+0x37/0x130
[140852.892847]  do_vfs_ioctl+0xa5/0x600
[140852.892850]  ? __fget+0x77/0xb0
[140852.892852]  SyS_ioctl+0x79/0x90
[140852.892855]  entry_SYSCALL_64_fastpath+0x1a/0xa9
[140852.892857] RIP: 0033:0x7f352d8c8cb7
[140852.892858] RSP: 002b:00007f3516ffc998 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[140852.892860] RAX: ffffffffffffffda RBX: 000000000000ae80 RCX: 00007f352d8c8cb7
[140852.892861] RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000015
[140852.892862] RBP: 0000558848ec1e30 R08: 00005588471fedf0 R09: 0000000000000000
[140852.892863] R10: 0000070000000088 R11: 0000000000000246 R12: 0000000000000000
[140852.892864] R13: 00007f353100c000 R14: 0000000000000000 R15: 0000558848ec1e30
[140852.892867] ---[ end trace 3ff1819b8b528602 ]---
Comment 3 Radim Krčmář 2017-06-12 15:23:12 UTC
This should be fixed since v4.12-rc4.  The patch in question is 5acc1ca4fb15 ("KVM: X86: Fix preempt the preemption timer cancel").

Note You need to log in before you can comment on or make changes to this bug.