INFO: rcu detected stall in kvm_vcpu_ioctl
7 views
Skip to first unread message
syzbot
Nov 13, 2019, 3:08:10 AM11/13/19
to syzkaller...@googlegroups.com
Hello,
syzbot found the following crash on:
HEAD commit: 4762bcd4 Linux 4.14.153
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=10d6f794e00000
kernel config: https://syzkaller.appspot.com/x/.config?x=eb84bd8b9cc2ec66
dashboard link: https://syzkaller.appspot.com/bug?extid=10980f8ef2d3d723c7a5
compiler: gcc (GCC) 9.0.0 20181231 (experimental)
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=13ad9bfce00000
IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+10980f...@syzkaller.appspotmail.com
INFO: rcu_preempt detected stalls on CPUs/tasks:
(detected by 0, t=10502 jiffies, g=8046, c=8045, q=41)
All QSes seen, last rcu_preempt kthread activity 10503
(4295114150-4295103647), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor.3 R running task 27584 21070 7082 0x00000000
Call Trace:
<IRQ>
sched_show_task kernel/sched/core.c:5168 [inline]
sched_show_task.cold+0x2f0/0x351 kernel/sched/core.c:5143
print_other_cpu_stall kernel/rcu/tree.c:1501 [inline]
check_cpu_stall kernel/rcu/tree.c:1616 [inline]
__rcu_pending kernel/rcu/tree.c:3390 [inline]
rcu_pending kernel/rcu/tree.c:3452 [inline]
rcu_check_callbacks.cold+0xcb1/0xd0a kernel/rcu/tree.c:2792
update_process_times+0x31/0x70 kernel/time/timer.c:1590
tick_sched_handle+0x85/0x160 kernel/time/tick-sched.c:161
tick_sched_timer+0x43/0x130 kernel/time/tick-sched.c:1219
__run_hrtimer kernel/time/hrtimer.c:1220 [inline]
__hrtimer_run_queues+0x270/0xbc0 kernel/time/hrtimer.c:1284
hrtimer_interrupt+0x1d8/0x5d0 kernel/time/hrtimer.c:1318
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
smp_apic_timer_interrupt+0x11c/0x5e0 arch/x86/kernel/apic/apic.c:1100
apic_timer_interrupt+0x96/0xa0 arch/x86/entry/entry_64.S:792
</IRQ>
RIP: 0010:__vmcs_readl arch/x86/kvm/vmx.c:1836 [inline]
RIP: 0010:vmcs_read32 arch/x86/kvm/vmx.c:1850 [inline]
RIP: 0010:vmx_read_guest_seg_ar+0x10b/0x250 arch/x86/kvm/vmx.c:2046
RSP: 0018:ffff8880977bf688 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
RAX: 0000000000000093 RBX: ffff88808b9d10c0 RCX: 000000000000000b
RDX: 0000000000004818 RSI: 0000000000000002 RDI: ffffffff86637b4c
RBP: ffff8880977bf6b0 R08: ffff88808b9d4948 R09: ffff88808b9d4a40
R10: ffffed101173a949 R11: ffff88808b9d4a4b R12: 0000000000000002
R13: 0000000000000002 R14: 00000000fff9ffef R15: ffff88808b9d10c0
vmx_get_cpl+0x51/0x80 arch/x86/kvm/vmx.c:4758
kvm_fetch_guest_virt+0x60/0x180 arch/x86/kvm/x86.c:4621
__do_insn_fetch_bytes+0x2aa/0x630 arch/x86/kvm/emulate.c:895
x86_decode_insn+0x15b0/0x4f60 arch/x86/kvm/emulate.c:5048
x86_emulate_instruction+0x604/0x1710 arch/x86/kvm/x86.c:5894
kvm_mmu_page_fault+0x1c5/0x300 arch/x86/kvm/mmu.c:4998
handle_ept_misconfig+0xa0/0x450 arch/x86/kvm/vmx.c:6959
vmx_handle_exit+0x20d/0x1330 arch/x86/kvm/vmx.c:9199
vcpu_enter_guest+0xf28/0x5220 arch/x86/kvm/x86.c:7264
vcpu_run arch/x86/kvm/x86.c:7327 [inline]
kvm_arch_vcpu_ioctl_run+0x318/0x1000 arch/x86/kvm/x86.c:7494
kvm_vcpu_ioctl+0x401/0xd10 arch/x86/kvm/../../../virt/kvm/kvm_main.c:2611
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x7ae/0x1060 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x8f/0xc0 fs/ioctl.c:692
do_syscall_64+0x1e8/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a219
RSP: 002b:00007fbdcddf0c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a219
RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000008
RBP: 000000000075c070 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fbdcddf16d4
R13: 00000000004c38b2 R14: 00000000004d7b70 R15: 00000000ffffffff
rcu_preempt kthread starved for 10540 jiffies! g8046 c8045 f0x2
RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt R running task 29824 8 2 0x80000000
Call Trace:
context_switch kernel/sched/core.c:2808 [inline]
__schedule+0x7b8/0x1cd0 kernel/sched/core.c:3384
schedule+0x92/0x1c0 kernel/sched/core.c:3428
schedule_timeout+0x43e/0xe10 kernel/time/timer.c:1746
rcu_gp_kthread+0xbf4/0x1ec0 kernel/rcu/tree.c:2255
kthread+0x319/0x430 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
---
This bug is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.
syzbot will keep track of this bug report. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.
syzbot can test patches for this bug, for details see:
https://goo.gl/tpsmEJ#testing-patches
syzbot found the following crash on:
HEAD commit: 4762bcd4 Linux 4.14.153
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=10d6f794e00000
kernel config: https://syzkaller.appspot.com/x/.config?x=eb84bd8b9cc2ec66
dashboard link: https://syzkaller.appspot.com/bug?extid=10980f8ef2d3d723c7a5
compiler: gcc (GCC) 9.0.0 20181231 (experimental)
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=13ad9bfce00000
IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+10980f...@syzkaller.appspotmail.com
INFO: rcu_preempt detected stalls on CPUs/tasks:
(detected by 0, t=10502 jiffies, g=8046, c=8045, q=41)
All QSes seen, last rcu_preempt kthread activity 10503
(4295114150-4295103647), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor.3 R running task 27584 21070 7082 0x00000000
Call Trace:
<IRQ>
sched_show_task kernel/sched/core.c:5168 [inline]
sched_show_task.cold+0x2f0/0x351 kernel/sched/core.c:5143
print_other_cpu_stall kernel/rcu/tree.c:1501 [inline]
check_cpu_stall kernel/rcu/tree.c:1616 [inline]
__rcu_pending kernel/rcu/tree.c:3390 [inline]
rcu_pending kernel/rcu/tree.c:3452 [inline]
rcu_check_callbacks.cold+0xcb1/0xd0a kernel/rcu/tree.c:2792
update_process_times+0x31/0x70 kernel/time/timer.c:1590
tick_sched_handle+0x85/0x160 kernel/time/tick-sched.c:161
tick_sched_timer+0x43/0x130 kernel/time/tick-sched.c:1219
__run_hrtimer kernel/time/hrtimer.c:1220 [inline]
__hrtimer_run_queues+0x270/0xbc0 kernel/time/hrtimer.c:1284
hrtimer_interrupt+0x1d8/0x5d0 kernel/time/hrtimer.c:1318
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
smp_apic_timer_interrupt+0x11c/0x5e0 arch/x86/kernel/apic/apic.c:1100
apic_timer_interrupt+0x96/0xa0 arch/x86/entry/entry_64.S:792
</IRQ>
RIP: 0010:__vmcs_readl arch/x86/kvm/vmx.c:1836 [inline]
RIP: 0010:vmcs_read32 arch/x86/kvm/vmx.c:1850 [inline]
RIP: 0010:vmx_read_guest_seg_ar+0x10b/0x250 arch/x86/kvm/vmx.c:2046
RSP: 0018:ffff8880977bf688 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
RAX: 0000000000000093 RBX: ffff88808b9d10c0 RCX: 000000000000000b
RDX: 0000000000004818 RSI: 0000000000000002 RDI: ffffffff86637b4c
RBP: ffff8880977bf6b0 R08: ffff88808b9d4948 R09: ffff88808b9d4a40
R10: ffffed101173a949 R11: ffff88808b9d4a4b R12: 0000000000000002
R13: 0000000000000002 R14: 00000000fff9ffef R15: ffff88808b9d10c0
vmx_get_cpl+0x51/0x80 arch/x86/kvm/vmx.c:4758
kvm_fetch_guest_virt+0x60/0x180 arch/x86/kvm/x86.c:4621
__do_insn_fetch_bytes+0x2aa/0x630 arch/x86/kvm/emulate.c:895
x86_decode_insn+0x15b0/0x4f60 arch/x86/kvm/emulate.c:5048
x86_emulate_instruction+0x604/0x1710 arch/x86/kvm/x86.c:5894
kvm_mmu_page_fault+0x1c5/0x300 arch/x86/kvm/mmu.c:4998
handle_ept_misconfig+0xa0/0x450 arch/x86/kvm/vmx.c:6959
vmx_handle_exit+0x20d/0x1330 arch/x86/kvm/vmx.c:9199
vcpu_enter_guest+0xf28/0x5220 arch/x86/kvm/x86.c:7264
vcpu_run arch/x86/kvm/x86.c:7327 [inline]
kvm_arch_vcpu_ioctl_run+0x318/0x1000 arch/x86/kvm/x86.c:7494
kvm_vcpu_ioctl+0x401/0xd10 arch/x86/kvm/../../../virt/kvm/kvm_main.c:2611
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x7ae/0x1060 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x8f/0xc0 fs/ioctl.c:692
do_syscall_64+0x1e8/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a219
RSP: 002b:00007fbdcddf0c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a219
RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000008
RBP: 000000000075c070 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fbdcddf16d4
R13: 00000000004c38b2 R14: 00000000004d7b70 R15: 00000000ffffffff
rcu_preempt kthread starved for 10540 jiffies! g8046 c8045 f0x2
RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt R running task 29824 8 2 0x80000000
Call Trace:
context_switch kernel/sched/core.c:2808 [inline]
__schedule+0x7b8/0x1cd0 kernel/sched/core.c:3384
schedule+0x92/0x1c0 kernel/sched/core.c:3428
schedule_timeout+0x43e/0xe10 kernel/time/timer.c:1746
rcu_gp_kthread+0xbf4/0x1ec0 kernel/rcu/tree.c:2255
kthread+0x319/0x430 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
---
This bug is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.
syzbot will keep track of this bug report. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.
syzbot can test patches for this bug, for details see:
https://goo.gl/tpsmEJ#testing-patches
syzbot
Mar 7, 2020, 4:23:12 PM3/7/20
to syzkaller...@googlegroups.com
syzbot has found a reproducer for the following crash on:
HEAD commit: 78d697fc Linux 4.14.172
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=168469fde00000
kernel config: https://syzkaller.appspot.com/x/.config?x=3484a1ea90b8523a
C reproducer: https://syzkaller.appspot.com/x/repro.c?x=12eff70de00000
IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+10980f...@syzkaller.appspotmail.com
INFO: rcu_preempt detected stalls on CPUs/tasks:
(detected by 1, t=10502 jiffies, g=3746, c=3745, q=13)
All QSes seen, last rcu_preempt kthread activity 10502 (4295093353-4295082851), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor421 R running task 28176 9059 7455 0x00000800
update_process_times+0x29/0x60 kernel/time/timer.c:1590
tick_sched_handle+0x7d/0x150 kernel/time/tick-sched.c:165
tick_sched_timer+0x3e/0x120 kernel/time/tick-sched.c:1223
__run_hrtimer kernel/time/hrtimer.c:1223 [inline]
__hrtimer_run_queues+0x25c/0xbb0 kernel/time/hrtimer.c:1287
hrtimer_interrupt+0x1e6/0x5e0 kernel/time/hrtimer.c:1321
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
smp_apic_timer_interrupt+0x117/0x5e0 arch/x86/kernel/apic/apic.c:1100
apic_timer_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:792
</IRQ>
RIP: 0010:vcpu_enter_guest+0xd66/0x5240 arch/x86/kvm/x86.c:7297
RSP: 0018:ffff888080e0fae0 EFLAGS: 00000297 ORIG_RAX: ffffffffffffff10
RAX: ffff8880a6dba6c0 RBX: ffff8880a6dba6c0 RCX: 1ffff11014db75ec
RDX: 0000000000000000 RSI: ffff8880a6dbaf40 RDI: ffff8880a6dbaf3c
RBP: ffff888080e0fbe0 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffff8880a8ae85c0
R13: ffff8880a8ae86a0 R14: ffff8880a8ae85e8 R15: fffffcf1740d116c
vcpu_run arch/x86/kvm/x86.c:7379 [inline]
kvm_arch_vcpu_ioctl_run+0x2fe/0xfd0 arch/x86/kvm/x86.c:7546
kvm_vcpu_ioctl+0x3df/0xc70 arch/x86/kvm/../../../virt/kvm/kvm_main.c:2661
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x44cb49
RSP: 002b:00007f86562d7ce8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000006e8a08 RCX: 000000000044cb49
RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000006
RBP: 00000000006e8a00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006e8a0c
R13: 00007ffd83b0303f R14: 00007f86562d89c0 R15: 20c49ba5e353f7cf
rcu_preempt kthread starved for 10502 jiffies! g3746 c3745 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt R running task 29920 8 2 0x80000000
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
HEAD commit: 78d697fc Linux 4.14.172
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=168469fde00000
kernel config: https://syzkaller.appspot.com/x/.config?x=3484a1ea90b8523a
dashboard link: https://syzkaller.appspot.com/bug?extid=10980f8ef2d3d723c7a5
compiler: gcc (GCC) 9.0.0 20181231 (experimental)
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=15c32e91e00000
compiler: gcc (GCC) 9.0.0 20181231 (experimental)
C reproducer: https://syzkaller.appspot.com/x/repro.c?x=12eff70de00000
IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+10980f...@syzkaller.appspotmail.com
INFO: rcu_preempt detected stalls on CPUs/tasks:
All QSes seen, last rcu_preempt kthread activity 10502 (4295093353-4295082851), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor421 R running task 28176 9059 7455 0x00000800
Call Trace:
<IRQ>
sched_show_task kernel/sched/core.c:5168 [inline]
sched_show_task.cold+0x2e3/0x347 kernel/sched/core.c:5143
<IRQ>
sched_show_task kernel/sched/core.c:5168 [inline]
print_other_cpu_stall kernel/rcu/tree.c:1501 [inline]
check_cpu_stall kernel/rcu/tree.c:1616 [inline]
__rcu_pending kernel/rcu/tree.c:3390 [inline]
rcu_pending kernel/rcu/tree.c:3452 [inline]
rcu_check_callbacks.cold+0xd3f/0xdaa kernel/rcu/tree.c:2792
check_cpu_stall kernel/rcu/tree.c:1616 [inline]
__rcu_pending kernel/rcu/tree.c:3390 [inline]
rcu_pending kernel/rcu/tree.c:3452 [inline]
update_process_times+0x29/0x60 kernel/time/timer.c:1590
tick_sched_handle+0x7d/0x150 kernel/time/tick-sched.c:165
tick_sched_timer+0x3e/0x120 kernel/time/tick-sched.c:1223
__run_hrtimer kernel/time/hrtimer.c:1223 [inline]
__hrtimer_run_queues+0x25c/0xbb0 kernel/time/hrtimer.c:1287
hrtimer_interrupt+0x1e6/0x5e0 kernel/time/hrtimer.c:1321
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
smp_apic_timer_interrupt+0x117/0x5e0 arch/x86/kernel/apic/apic.c:1100
apic_timer_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:792
</IRQ>
RIP: 0010:vcpu_enter_guest+0xd66/0x5240 arch/x86/kvm/x86.c:7297
RSP: 0018:ffff888080e0fae0 EFLAGS: 00000297 ORIG_RAX: ffffffffffffff10
RAX: ffff8880a6dba6c0 RBX: ffff8880a6dba6c0 RCX: 1ffff11014db75ec
RDX: 0000000000000000 RSI: ffff8880a6dbaf40 RDI: ffff8880a6dbaf3c
RBP: ffff888080e0fbe0 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffff8880a8ae85c0
R13: ffff8880a8ae86a0 R14: ffff8880a8ae85e8 R15: fffffcf1740d116c
vcpu_run arch/x86/kvm/x86.c:7379 [inline]
kvm_arch_vcpu_ioctl_run+0x2fe/0xfd0 arch/x86/kvm/x86.c:7546
kvm_vcpu_ioctl+0x3df/0xc70 arch/x86/kvm/../../../virt/kvm/kvm_main.c:2661
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0x75a/0xfe0 fs/ioctl.c:684
file_ioctl fs/ioctl.c:500 [inline]
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x44cb49
RSP: 002b:00007f86562d7ce8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000006e8a08 RCX: 000000000044cb49
RDX: 0000000000000000 RSI: 000000000000ae80 RDI: 0000000000000006
RBP: 00000000006e8a00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006e8a0c
R13: 00007ffd83b0303f R14: 00007f86562d89c0 R15: 20c49ba5e353f7cf
rcu_preempt kthread starved for 10502 jiffies! g3746 c3745 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt R running task 29920 8 2 0x80000000
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Reply all
Reply to author
Forward
0 new messages