INFO: rcu_sched self-detected stall on CPU
INFO: rcu_sched detected stalls on CPUs/tasks:
0-....: (125000 ticks this GP) idle=df6/1/4611686018427387906 softirq=140642/140642 fqs=31229
0-....: (125000 ticks this GP) idle=df6/1/4611686018427387906 softirq=140642/140642 fqs=31229
(t=125002 jiffies g=77120 c=77119 q=775)
(detected by 1, t=125002 jiffies, g=77120, c=77119, q=775)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 21600 Comm: syz-executor2 Not tainted 4.15.0-rc8-next-20180116+ #98
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:rep_nop arch/x86/include/asm/processor.h:659 [inline]
RIP: 0010:cpu_relax arch/x86/include/asm/processor.h:664 [inline]
RIP: 0010:virt_spin_lock arch/x86/include/asm/qspinlock.h:69 [inline]
RIP: 0010:native_queued_spin_lock_slowpath+0x1c4/0xfa0 kernel/locking/qspinlock.c:305
RSP: 0018:ffff8801db207030 EFLAGS: 00000002
RAX: 0000000000000001 RBX: ffffffff86945a80 RCX: dffffc0000000000
RDX: 0000000000000004 RSI: ffff8801db207100 RDI: ffffffff86945a80
RBP: ffff8801db2073a8 R08: 1ffff1003b640dc1 R09: 000000000000000c
R10: ffff8801db2072c8 R11: 0000000000000040 R12: ffffed003b640e20
R13: 1ffff1003b640e0c R14: ffff8801db207380 R15: 0000000000000003
FS: 00007fa5a6878700(0000) GS:ffff8801db200000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000020e0d000 CR3: 00000001d8bd7002 CR4: 00000000001626f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:668 [inline]
queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:30 [inline]
queued_spin_lock include/asm-generic/qspinlock.h:90 [inline]
do_raw_spin_lock+0x185/0x220 kernel/locking/spinlock_debug.c:113
__raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:117 [inline]
_raw_spin_lock_irqsave+0x9e/0xc0 kernel/locking/spinlock.c:152
rcu_dump_cpu_stacks+0x9d/0x1de kernel/rcu/tree.c:1372
print_cpu_stall kernel/rcu/tree.c:1524 [inline]
check_cpu_stall.isra.61+0xbb8/0x15b0 kernel/rcu/tree.c:1592
__rcu_pending kernel/rcu/tree.c:3361 [inline]
rcu_pending kernel/rcu/tree.c:3423 [inline]
rcu_check_callbacks+0x238/0xd20 kernel/rcu/tree.c:2763
update_process_times+0x30/0x60 kernel/time/timer.c:1628
tick_sched_handle+0x85/0x160 kernel/time/tick-sched.c:162
tick_sched_timer+0x42/0x120 kernel/time/tick-sched.c:1194
__run_hrtimer kernel/time/hrtimer.c:1210 [inline]
__hrtimer_run_queues+0x358/0xe20 kernel/time/hrtimer.c:1274
hrtimer_interrupt+0x1c2/0x5e0 kernel/time/hrtimer.c:1308
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1025 [inline]
smp_apic_timer_interrupt+0x14a/0x700 arch/x86/kernel/apic/apic.c:1050
apic_timer_interrupt+0xa9/0xb0 arch/x86/entry/entry_64.S:926
RIP: 0010:loop_set_fd drivers/block/loop.c:886 [inline]
RIP: 0010:lo_ioctl+0x635/0x1b70 drivers/block/loop.c:1358
RSP: 0018:ffff8801d85ffaa0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff11
RAX: 0000000000010000 RBX: dffffc0000000000 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffffc90001348000 RDI: ffff8801d58f0a48
RBP: ffff8801d85ffb18 R08: 0000000000000000 R09: 1ffff1003b0bff05
R10: ffff8801d85ff7f0 R11: 0000000000000001 R12: ffff8801d58f0900
R13: ffff8801bcf4d6c0 R14: ffff8801cee81740 R15: ffff8801bcf4d810
__blkdev_driver_ioctl block/ioctl.c:303 [inline]
blkdev_ioctl+0x1759/0x1e00 block/ioctl.c:601
block_ioctl+0xde/0x120 fs/block_dev.c:1859
vfs_ioctl fs/ioctl.c:46 [inline]
do_vfs_ioctl+0x1b1/0x1520 fs/ioctl.c:686
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x8f/0xc0 fs/ioctl.c:692
entry_SYSCALL_64_fastpath+0x29/0xa0
RIP: 0033:0x452df9
RSP: 002b:00007fa5a6877c58 EFLAGS: 00000212 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007fa5a6878700 RCX: 0000000000452df9
RDX: 0000000000000014 RSI: 0000000000004c00 RDI: 0000000000000015
RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000
R13: 0000000000a2f7ef R14: 00007fa5a68789c0 R15: 0000000000000000
Code: 5c 00 00 00 00 48 83 e7 f8 48 29 f8 8d 48 64 31 c0 c1 e9 03 f3 48 ab 48 81 c4 50 03 00 00 5b 41 5c 41 5d 41 5e 41 5f 5d c3 f3 90 67 ff ff ff 81 fe 00 01 00 00 0f 84 9d 06 00 00 bf 01 01 00
NMI backtrace for cpu 0
CPU: 0 PID: 21600 Comm: syz-executor2 Not tainted 4.15.0-rc8-next-20180116+ #98
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:17 [inline]
dump_stack+0x194/0x257 lib/dump_stack.c:53
nmi_cpu_backtrace+0x1d2/0x210 lib/nmi_backtrace.c:103
nmi_trigger_cpumask_backtrace+0x122/0x180 lib/nmi_backtrace.c:62
arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
trigger_single_cpu_backtrace include/linux/nmi.h:156 [inline]
rcu_dump_cpu_stacks+0x186/0x1de kernel/rcu/tree.c:1375
print_cpu_stall kernel/rcu/tree.c:1524 [inline]
check_cpu_stall.isra.61+0xbb8/0x15b0 kernel/rcu/tree.c:1592
__rcu_pending kernel/rcu/tree.c:3361 [inline]
rcu_pending kernel/rcu/tree.c:3423 [inline]
rcu_check_callbacks+0x238/0xd20 kernel/rcu/tree.c:2763
update_process_times+0x30/0x60 kernel/time/timer.c:1628
tick_sched_handle+0x85/0x160 kernel/time/tick-sched.c:162
tick_sched_timer+0x42/0x120 kernel/time/tick-sched.c:1194
__run_hrtimer kernel/time/hrtimer.c:1210 [inline]
__hrtimer_run_queues+0x358/0xe20 kernel/time/hrtimer.c:1274
hrtimer_interrupt+0x1c2/0x5e0 kernel/time/hrtimer.c:1308
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1025 [inline]
smp_apic_timer_interrupt+0x14a/0x700 arch/x86/kernel/apic/apic.c:1050
apic_timer_interrupt+0xa9/0xb0 arch/x86/entry/entry_64.S:926
RIP: 0010:loop_set_fd drivers/block/loop.c:886 [inline]
RIP: 0010:lo_ioctl+0x635/0x1b70 drivers/block/loop.c:1358
RSP: 0018:ffff8801d85ffaa0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff11
RAX: 0000000000010000 RBX: dffffc0000000000 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffffc90001348000 RDI: ffff8801d58f0a48
RBP: ffff8801d85ffb18 R08: 0000000000000000 R09: 1ffff1003b0bff05
R10: ffff8801d85ff7f0 R11: 0000000000000001 R12: ffff8801d58f0900
R13: ffff8801bcf4d6c0 R14: ffff8801cee81740 R15: ffff8801bcf4d810
__blkdev_driver_ioctl block/ioctl.c:303 [inline]
blkdev_ioctl+0x1759/0x1e00 block/ioctl.c:601
block_ioctl+0xde/0x120 fs/block_dev.c:1859
vfs_ioctl fs/ioctl.c:46 [inline]
do_vfs_ioctl+0x1b1/0x1520 fs/ioctl.c:686
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x8f/0xc0 fs/ioctl.c:692
entry_SYSCALL_64_fastpath+0x29/0xa0
RIP: 0033:0x452df9
RSP: 002b:00007fa5a6877c58 EFLAGS: 00000212 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007fa5a6878700 RCX: 0000000000452df9
RDX: 0000000000000014 RSI: 0000000000004c00 RDI: 0000000000000015
RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000
R13: 0000000000a2f7ef R14: 00007fa5a68789c0 R15: 0000000000000000
INFO: task syz-executor3:21613 blocked for more than 120 seconds.
Not tainted 4.15.0-rc8-next-20180116+ #98
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor3 D24400 21613 4757 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2845 [inline]
__schedule+0x8eb/0x2060 kernel/sched/core.c:3421
schedule+0xf5/0x430 kernel/sched/core.c:3480
schedule_preempt_disabled+0x10/0x20 kernel/sched/core.c:3538
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0xaad/0x1a80 kernel/locking/mutex.c:893
mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908
perf_trace_init+0x58/0xab0 kernel/trace/trace_event_perf.c:216
perf_tp_event_init+0x7d/0xf0 kernel/events/core.c:7970
perf_try_init_event+0xc9/0x1f0 kernel/events/core.c:9203
perf_init_event kernel/events/core.c:9241 [inline]
perf_event_alloc+0x1cc6/0x2b00 kernel/events/core.c:9505
SYSC_perf_event_open+0x842/0x2f10 kernel/events/core.c:9960
SyS_perf_event_open+0x39/0x50 kernel/events/core.c:9846
entry_SYSCALL_64_fastpath+0x29/0xa0
RIP: 0033:0x452df9
RSP: 002b:00007f2c0a08ac58 EFLAGS: 00000212 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 000000000071bf58 RCX: 0000000000452df9
RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 000000002025c000
RBP: 0000000000000208 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000212 R12: 00000000006f1160
R13: 00000000ffffffff R14: 00007f2c0a08b6d4 R15: 000000000000000f
Showing all locks held in the system:
2 locks held by khungtaskd/746:
#0: (rcu_read_lock){....}, at: [<00000000ba64d6d1>] check_hung_uninterruptible_tasks kernel/hung_task.c:175 [inline]
#0: (rcu_read_lock){....}, at: [<00000000ba64d6d1>] watchdog+0x1c5/0xd60 kernel/hung_task.c:249
#1: (tasklist_lock){.+.+}, at: [<0000000003d12381>] debug_show_all_locks+0xd3/0x400 kernel/locking/lockdep.c:4464
4 locks held by rs:main Q:Reg/3520:
#0: (&f->f_pos_lock){+.+.}, at: [<0000000043b8be97>] __fdget_pos+0x12b/0x190 fs/file.c:765
#1: (sb_writers#4){.+.+}, at: [<00000000f5d60b43>] file_start_write include/linux/fs.h:2705 [inline]
#1: (sb_writers#4){.+.+}, at: [<00000000f5d60b43>] vfs_write+0x407/0x510 fs/read_write.c:543
#2: (&sb->s_type->i_mutex_key#10){+.+.}, at: [<00000000ba87f3bb>] inode_trylock include/linux/fs.h:733 [inline]
#2: (&sb->s_type->i_mutex_key#10){+.+.}, at: [<00000000ba87f3bb>] ext4_file_write_iter+0x21f/0x10c0 fs/ext4/file.c:232
#3: (jbd2_handle){++++}, at: [<000000006514dcaa>] start_this_handle+0x488/0x1080 fs/jbd2/transaction.c:385
2 locks held by getty/3645:
#0: (&tty->ldisc_sem){++++}, at: [<00000000099118c6>] ldsem_down_read+0x37/0x40 drivers/tty/tty_ldsem.c:365