Bug 22103

Summary: kvm soft lockup
Product: Sisyphus Reporter: Anton Farygin <rider>
Component: kernel-image-std-defAssignee: Vitaly Chikunov <vt>
Status: CLOSED FIXED QA Contact: qa-sisyphus
Severity: major    
Priority: P3 CC: kernelbot, placeholder, vt
Version: unstable   
Hardware: all   
OS: Linux   

Description Anton Farygin 2009-10-29 10:23:31 MSK 
kernel-image-std-def-2.6.30-alt14

CPU:
2xIntel(R) Xeon(R) CPU           E5504  @ 2.00GHz

Запускаю:
$kvm -boot c -hda a.img -curses -smp 4 

Получаю в dmesg:
[ 2541.212507] BUG: unable to handle kernel paging request at 0000000000011540
[ 2541.220323] IP: [<ffffffffa042f43f>] vmx_vcpu_run+0x19f/0xaa1 [kvm_intel]
[ 2541.227920] PGD 26d045067 PUD 2528ee067 PMD 0 
[ 2541.232966] Oops: 0002 [#1] SMP 
[ 2541.236591] last sysfs file: /sys/devices/pci0000:00/0000:00:1f.5/host3/target3:0:0/3:0:0:0/block/sda/removable
[ 2541.247851] CPU 3 <1>BUG: unable to handle kernel paging request at 0000000000041292
[ 2541.256611] IP: [<0000000000041292>] 0x41292
[ 2541.261389] PGD 26d045067 PUD 2528ee067 PMD 0 
[ 2541.265586] Oops: 0010 [#2] SMP 
[ 2541.265588] last sysfs file: /sys/devices/pci0000:00/0000:00:1f.5/host3/target3:0:0/3:0:0:0/block/sda/removable
[ 2541.265590] CPU 3 
[ 2541.265591] Modules linked in: nfs fscache kvm_intel kvm ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp iptable_filter ip_tables x_tables bridge stp ib_ucm rdma_ucm rdma_cm iw_cm ib_uverbs ib_umad ib_cm ib_sa ib_mad ib_core ib_addr ipv6 nfsd lockd nfs_acl auth_rpcgss exportfs ipmi_si ipmi_msghandler sunrpc af_packet dm_mod joydev usbhid hid rtc_cmos rtc_core ehci_hcd processor uhci_hcd usbcore thermal sg i2c_i801 e1000e i2c_core pcspkr evdev rtc_lib button ext3 jbd mbcache ata_generic ata_piix pata_acpi libata sd_mod crc_t10dif scsi_mod
[ 2541.265617] Pid: 7564, comm: kvm Not tainted 2.6.30-std-def-alt14 #1 S5500BC
[ 2541.265619] RIP: 0010:[<0000000000041292>]  [<0000000000041292>] 0x41292
[ 2541.265622] RSP: 0018:ffff8801699cf998  EFLAGS: 00010096
[ 2541.265624] RAX: 0000000000000018 RBX: 00000000df87bf08 RCX: 0000000000000000
[ 2541.265626] RDX: ffffc2000003c000 RSI: 0000000000000046 RDI: ffffffff807c7490
[ 2541.265628] RBP: ffff8801699cf9a8 R08: 000000005c983f5f R09: 0000000000000003
[ 2541.265630] R10: 000000000000000a R11: 0000000000000000 R12: ffff8801699cf9a8
[ 2541.265632] R13: ffffffff8022d45c R14: ffff8801699cf988 R15: 0000000000000002
[ 2541.265634] FS:  00007faff8fb3910(0000) GS:ffffc2000003c000(0000) knlGS:0000000000000000
[ 2541.265636] CS:  0010 DS: 0018 ES: 0018 CR0: 000000008005003b
[ 2541.265637] CR2: 0000000000041292 CR3: 000000026d8d1000 CR4: 00000000000026e0
[ 2541.265640] DR0: ffffffff8053f1f0 DR1: 0000000000000000 DR2: 0000000000000000
[ 2541.265641] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[ 2541.265644] Process kvm (pid: 7564, threadinfo ffff880164162000, task ffff88016996c680)
[ 2541.265645] Stack:
[ 2541.265646]  ffff88016c154640 0000000000000002 ffff8801699cf9f8 ffffffff8022d6c5
[ 2541.265648]  0000000000000001 0000000000000000 0000000000000000 00000000df87bf08
[ 2541.265651]  0000000000000002 ffff8801699cfb58 ffff88016c154640 0000000000030001
[ 2541.265655] Call Trace:
[ 2541.265657] Code:  Bad RIP value.
[ 2541.265658] RIP  [<0000000000041292>] 0x41292
[ 2541.265661]  RSP <ffff8801699cf998>
[ 2541.265662] CR2: 0000000000041292
[ 2541.275911] ---[ end trace b64e7487897a3c30 ]---
[ 2645.700267] BUG: soft lockup - CPU#4 stuck for 61s! [kvm:7613]
[ 2645.706778] Modules linked in: nfs fscache kvm_intel kvm ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp iptable_filter ip_tables x_tables bridge stp ib_ucm rdma_ucm rdma_cm iw_cm ib_uverbs ib_umad ib_cm ib_sa ib_mad ib_core ib_addr ipv6 nfsd lockd nfs_acl auth_rpcgss exportfs ipmi_si ipmi_msghandler sunrpc af_packet dm_mod joydev usbhid hid rtc_cmos rtc_core ehci_hcd processor uhci_hcd usbcore thermal sg i2c_i801 e1000e i2c_core pcspkr evdev rtc_lib button ext3 jbd mbcache ata_generic ata_piix pata_acpi libata sd_mod crc_t10dif scsi_mod
[ 2645.766743] CPU 4:
[ 2645.768996] Modules linked in: nfs fscache kvm_intel kvm ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp iptable_filter ip_tables x_tables bridge stp ib_ucm rdma_ucm rdma_cm iw_cm ib_uverbs ib_umad ib_cm ib_sa ib_mad ib_core ib_addr ipv6 nfsd lockd nfs_acl auth_rpcgss exportfs ipmi_si ipmi_msghandler sunrpc af_packet dm_mod joydev usbhid hid rtc_cmos rtc_core ehci_hcd processor uhci_hcd usbcore thermal sg i2c_i801 e1000e i2c_core pcspkr evdev rtc_lib button ext3 jbd mbcache ata_generic ata_piix pata_acpi libata sd_mod crc_t10dif scsi_mod
[ 2645.829084] Pid: 7613, comm: kvm Tainted: G      D    2.6.30-std-def-alt14 #1 S5500BC
[ 2645.837825] RIP: 0010:[<ffffffff8023214e>]  [<ffffffff8023214e>] flush_tlb_others_ipi+0x10e/0x120
[ 2645.847745] RSP: 0000:ffff88026d025888  EFLAGS: 00000202
[ 2645.853674] RAX: 0000000000000010 RBX: ffff88026d0258c8 RCX: 0000000000000006
[ 2645.861639] RDX: 0000000000000000 RSI: 0000000000000040 RDI: 0000000000000040
[ 2645.869604] RBP: ffffffff8020ca4e R08: 0000000000000000 R09: ffffffff807c5de0
[ 2645.869605] R10: 0000000000000002 R11: 0000000000000004 R12: 0000000000000020
[ 2645.869607] R13: ffff88026d025948 R14: ffff88026d025960 R15: 0000000000000001
[ 2645.869609] FS:  00007fdffa70d6f0(0000) GS:ffffc20000050000(0000) knlGS:0000000000000000
[ 2645.869612] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 2645.869613] CR2: 0000000003c36000 CR3: 00000002528b7000 CR4: 00000000000026e0
[ 2645.869615] DR0: ffffffff8053f1f0 DR1: 0000000000000000 DR2: 0000000000000000
[ 2645.869617] DR3: 0000000000000000 DR6: 00000000ffff0ff1 DR7: 0000000000000400
[ 2645.869619] Call Trace:
[ 2645.869622]  [<ffffffff80232137>] ? flush_tlb_others_ipi+0xf7/0x120
[ 2645.869625]  [<ffffffff802322e9>] ? flush_tlb_page+0x59/0x90
[ 2645.869628]  [<ffffffff80231690>] ? ptep_clear_flush_young+0x20/0x30
[ 2645.869632]  [<ffffffff802d4946>] ? page_referenced_one+0xb6/0x130
[ 2645.869635]  [<ffffffff802d5c0f>] ? page_referenced+0xff/0x170
[ 2645.869639]  [<ffffffff802bfc95>] ? shrink_active_list+0x125/0x420
[ 2645.869643]  [<ffffffff802c086f>] ? shrink_zone+0x2df/0x380
[ 2645.869647]  [<ffffffff80235738>] ? resched_task+0x68/0x80
[ 2645.869649]  [<ffffffff802c0d13>] ? zone_reclaim+0x283/0x3b0
[ 2645.869653]  [<ffffffff802be610>] ? isolate_pages_global+0x0/0x270
[ 2645.869655]  [<ffffffff802b7e31>] ? get_page_from_freelist+0x311/0x7f0
[ 2645.869658]  [<ffffffff802b8425>] ? __alloc_pages_internal+0xf5/0x540
[ 2645.869662]  [<ffffffff802e2515>] ? alloc_page_vma+0x75/0x100
[ 2645.869665]  [<ffffffff802cd866>] ? handle_mm_fault+0x2a6/0x860
[ 2645.869670]  [<ffffffff80543f7c>] ? do_page_fault+0x19c/0x300
[ 2645.869673]  [<ffffffff80541adf>] ? page_fault+0x1f/0x30
[ 2711.199238] BUG: soft lockup - CPU#4 stuck for 61s! [kvm:7613]
[ 2711.205748] Modules linked in: nfs fscache kvm_intel kvm ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp iptable_filter ip_tables x_tables bridge stp ib_ucm rdma_ucm rdma_cm iw_cm ib_uverbs ib_umad ib_cm ib_sa ib_mad ib_core ib_addr ipv6 nfsd lockd nfs_acl auth_rpcgss exportfs ipmi_si ipmi_msghandler sunrpc af_packet dm_mod joydev usbhid hid rtc_cmos rtc_core ehci_hcd processor uhci_hcd usbcore thermal sg i2c_i801 e1000e i2c_core pcspkr evdev rtc_lib button ext3 jbd mbcache ata_generic ata_piix pata_acpi libata sd_mod crc_t10dif scsi_mod
[ 2711.259405] CPU 4:
[ 2711.259405] Modules linked in: nfs fscache kvm_intel kvm ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_tcpudp iptable_filter ip_tables x_tables bridge stp ib_ucm rdma_ucm rdma_cm iw_cm ib_uverbs ib_umad ib_cm ib_sa ib_mad ib_core ib_addr ipv6 nfsd lockd nfs_acl auth_rpcgss exportfs ipmi_si ipmi_msghandler sunrpc af_packet dm_mod joydev usbhid hid rtc_cmos rtc_core ehci_hcd processor uhci_hcd usbcore thermal sg i2c_i801 e1000e i2c_core pcspkr evdev rtc_lib button ext3 jbd mbcache ata_generic ata_piix pata_acpi libata sd_mod crc_t10dif scsi_mod
[ 2711.259429] Pid: 7613, comm: kvm Tainted: G      D    2.6.30-std-def-alt14 #1 S5500BC
[ 2711.259431] RIP: 0010:[<ffffffff80232148>]  [<ffffffff80232148>] flush_tlb_others_ipi+0x108/0x120
[ 2711.259438] RSP: 0000:ffff88026d025888  EFLAGS: 00000202
[ 2711.259440] RAX: 0000000000000010 RBX: ffff88026d0258c8 RCX: 0000000000000006
[ 2711.259442] RDX: 0000000000000000 RSI: 0000000000000040 RDI: 0000000000000040
[ 2711.259443] RBP: ffffffff8020ca4e R08: 0000000000000000 R09: ffffffff807c5de0
[ 2711.259445] R10: 0000000000000002 R11: 0000000000000004 R12: 0000000000000020
[ 2711.259447] R13: ffff88026d025948 R14: ffff88026d025960 R15: 0000000000000001
[ 2711.259449] FS:  00007fdffa70d6f0(0000) GS:ffffc20000050000(0000) knlGS:0000000000000000
[ 2711.259451] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 2711.259453] CR2: 0000000003c36000 CR3: 00000002528b7000 CR4: 00000000000026e0
[ 2711.259455] DR0: ffffffff8053f1f0 DR1: 0000000000000000 DR2: 0000000000000000
[ 2711.259457] DR3: 0000000000000000 DR6: 00000000ffff0ff1 DR7: 0000000000000400
[ 2711.259458] Call Trace:
[ 2711.259462]  [<ffffffff80232137>] ? flush_tlb_others_ipi+0xf7/0x120
[ 2711.259464]  [<ffffffff802322e9>] ? flush_tlb_page+0x59/0x90
[ 2711.259467]  [<ffffffff80231690>] ? ptep_clear_flush_young+0x20/0x30
[ 2711.259471]  [<ffffffff802d4946>] ? page_referenced_one+0xb6/0x130
[ 2711.259476]  [<ffffffff802d5c0f>] ? page_referenced+0xff/0x170
[ 2711.259485]  [<ffffffff802bfc95>] ? shrink_active_list+0x125/0x420
[ 2711.259489]  [<ffffffff802c086f>] ? shrink_zone+0x2df/0x380
[ 2711.259492]  [<ffffffff80235738>] ? resched_task+0x68/0x80
[ 2711.259495]  [<ffffffff802c0d13>] ? zone_reclaim+0x283/0x3b0
[ 2711.259498]  [<ffffffff802be610>] ? isolate_pages_global+0x0/0x270
[ 2711.259501]  [<ffffffff802b7e31>] ? get_page_from_freelist+0x311/0x7f0
[ 2711.259504]  [<ffffffff802b8425>] ? __alloc_pages_internal+0xf5/0x540
[ 2711.259508]  [<ffffffff802e2515>] ? alloc_page_vma+0x75/0x100
[ 2711.259511]  [<ffffffff802cd866>] ? handle_mm_fault+0x2a6/0x860
[ 2711.259516]  [<ffffffff80543f7c>] ? do_page_fault+0x19c/0x300
[ 2711.259519]  [<ffffffff80541adf>] ? page_fault+0x1f/0x30
Comment 1 Michail Yakushin 2010-06-22 15:49:06 MSD 
Ошибка актуальна?
Comment 2 Michail Yakushin 2010-07-02 10:45:43 MSD 
наверное давно исправлена.