Hello,
syzbot found the following crash on:
HEAD commit: 1ec8f1f0 Linux 4.14.111
git tree: linux-4.14.y
console output:
https://syzkaller.appspot.com/x/log.txt?x=136ed6fd200000
kernel config:
https://syzkaller.appspot.com/x/.config?x=fdadf290ea9fc6f9
dashboard link:
https://syzkaller.appspot.com/bug?extid=0f14e80e289d4997740f
compiler: gcc (GCC) 9.0.0 20181231 (experimental)
Unfortunately, I don't have any reproducer for this crash yet.
IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by:
syzbot+0f14e8...@syzkaller.appspotmail.com
audit: type=1804 audit(1555082859.632:93): pid=26137 uid=0 auid=4294967295
ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023
op="invalid_pcr" cause="open_writers" comm="syz-executor.2"
name="/root/syzkaller-testdir770443220/syzkaller.vnfIW8/454/bus" dev="sda1"
ino=18172 res=1
INFO: rcu_preempt self-detected stall on CPU
1-...: (1 GPs behind) idle=dae/140000000000001/0 softirq=97728/97732 fqs=1
(t=10500 jiffies g=32128 c=32127 q=171)
rcu_preempt kthread starved for 10496 jiffies! g32128 c32127 f0x0
RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt R running task 29776 8 2 0x80000000
Call Trace:
context_switch kernel/sched/core.c:2807 [inline]
__schedule+0x7be/0x1cf0 kernel/sched/core.c:3383
schedule+0x92/0x1c0 kernel/sched/core.c:3427
schedule_timeout+0x43e/0xe10 kernel/time/timer.c:1744
rcu_gp_kthread+0xbf4/0x1ec0 kernel/rcu/tree.c:2255
kthread+0x31c/0x430 kernel/kthread.c:232
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:402
NMI backtrace for cpu 1
CPU: 1 PID: 26128 Comm: syz-executor.4 Not tainted 4.14.111 #1
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
Call Trace:
<IRQ>
__dump_stack lib/dump_stack.c:17 [inline]
dump_stack+0x138/0x19c lib/dump_stack.c:53
nmi_cpu_backtrace.cold+0x57/0x94 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x141/0x189 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:158 [inline]
rcu_dump_cpu_stacks+0x186/0x1d2 kernel/rcu/tree.c:1396
print_cpu_stall kernel/rcu/tree.c:1542 [inline]
check_cpu_stall kernel/rcu/tree.c:1610 [inline]
__rcu_pending kernel/rcu/tree.c:3390 [inline]
rcu_pending kernel/rcu/tree.c:3452 [inline]
rcu_check_callbacks.cold+0x43d/0xd0a kernel/rcu/tree.c:2792
update_process_times+0x31/0x70 kernel/time/timer.c:1588
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+0x273/0xbd0 kernel/time/hrtimer.c:1284
hrtimer_interrupt+0x1d8/0x5d0 kernel/time/hrtimer.c:1318
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1037 [inline]
smp_apic_timer_interrupt+0x12c/0x600 arch/x86/kernel/apic/apic.c:1062
apic_timer_interrupt+0x8e/0xa0 arch/x86/entry/entry_64.S:787
</IRQ>
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:778
[inline]
RIP: 0010:lock_acquire+0x1ea/0x430 kernel/locking/lockdep.c:3997
RSP: 0018:ffff88809974f2a8 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10
RAX: 1ffffffff0ee2965 RBX: ffff88806dbbe5c0 RCX: 000000001ffc7b3a
RDX: dffffc0000000000 RSI: ffff88806dbbee90 RDI: 0000000000000282
RBP: ffff88809974f2f0 R08: 0000000000000000 R09: ffff88806dbbeeb0
R10: ffff88806dbbee90 R11: ffff88806dbbe5c0 R12: ffffffff8778ed60
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002
rcu_lock_acquire include/linux/rcupdate.h:244 [inline]
rcu_read_lock include/linux/rcupdate.h:631 [inline]
avc_has_perm_noaudit+0xca/0x420 security/selinux/avc.c:1106
cred_has_capability+0x12e/0x290 security/selinux/hooks.c:1745
selinux_capable+0x36/0x40 security/selinux/hooks.c:2225
security_capable+0x88/0xc0 security/security.c:279
ns_capable_common+0x73/0x160 kernel/capability.c:375
ns_capable kernel/capability.c:397 [inline]
capable+0x22/0x30 kernel/capability.c:431
should_remove_suid+0xa6/0xe0 fs/inode.c:1767
dentry_needs_remove_privs.part.0+0x18/0x60 fs/inode.c:1788
dentry_needs_remove_privs fs/inode.c:1833 [inline]
file_remove_privs+0x1f6/0x430 fs/inode.c:1824
__generic_file_write_iter+0x15e/0x5b0 mm/filemap.c:3095
ext4_file_write_iter+0x2c0/0xfd0 fs/ext4/file.c:264
call_write_iter include/linux/fs.h:1774 [inline]
do_iter_readv_writev+0x41e/0x680 fs/read_write.c:674
do_iter_write fs/read_write.c:953 [inline]
do_iter_write+0x154/0x550 fs/read_write.c:934
vfs_iter_write+0x77/0xb0 fs/read_write.c:966
iter_file_splice_write+0x578/0xae0 fs/splice.c:749
do_splice_from fs/splice.c:851 [inline]
direct_splice_actor+0x126/0x1a0 fs/splice.c:1018
splice_direct_to_actor+0x2a1/0x7b0 fs/splice.c:973
do_splice_direct+0x18d/0x230 fs/splice.c:1061
do_sendfile+0x4db/0xbd0 fs/read_write.c:1440
SYSC_sendfile64 fs/read_write.c:1501 [inline]
SyS_sendfile64+0x102/0x110 fs/read_write.c:1487
do_syscall_64+0x1eb/0x630 arch/x86/entry/common.c:289
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x458c29
RSP: 002b:00007fc820c65c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000458c29
RDX: 0000000000000000 RSI: 0000000000000007 RDI: 0000000000000005
RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 00008000fffffffe R11: 0000000000000246 R12: 00007fc820c666d4
R13: 00000000004c5c9c R14: 00000000004da240 R15: 00000000ffffffff
INFO: rcu_sched detected stalls on CPUs/tasks:
1-...: (1 GPs behind) idle=dae/140000000000000/0 softirq=97727/97732 fqs=1
(detected by 0, t=10555 jiffies, g=17001, c=17000, q=3)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 26128 Comm: syz-executor.4 Not tainted 4.14.111 #1
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
task: ffff88806dbbe5c0 task.stack: ffff888099748000
RIP: 0010:__sanitizer_cov_trace_pc+0x51/0x60 kernel/kcov.c:93
RSP: 0018:ffff88809974f248 EFLAGS: 00000246
RAX: 0000000000040000 RBX: 0000000000000002 RCX: ffffc90009a53000
RDX: 0000000000040000 RSI: ffffffff81c24948 RDI: 0000000000000282
RBP: ffff88809974f248 R08: ffff888077ee5348 R09: 0000000000000002
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: ffff888215cfe400 R14: ffff888017d2c064 R15: ffff8880943858b0
FS: 00007fc820c66700(0000) GS:ffff8880aef00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000001b2dd33000 CR3: 000000005df03000 CR4: 00000000001406e0
Call Trace:
brelse include/linux/buffer_head.h:295 [inline]
ext4_do_update_inode fs/ext4/inode.c:5179 [inline]
ext4_mark_iloc_dirty+0x16d8/0x2900 fs/ext4/inode.c:5691
ext4_mark_inode_dirty+0x1f0/0x870 fs/ext4/inode.c:5870
ext4_dirty_inode+0x73/0xa0 fs/ext4/inode.c:5901
__mark_inode_dirty+0x550/0x1040 fs/fs-writeback.c:2130
mark_inode_dirty include/linux/fs.h:2016 [inline]
generic_write_end+0x1b7/0x290 fs/buffer.c:2218
ext4_da_write_end+0x347/0x8f0 fs/ext4/inode.c:3181
generic_perform_write+0x2a2/0x490 mm/filemap.c:3035
__generic_file_write_iter+0x239/0x5b0 mm/filemap.c:3149
ext4_file_write_iter+0x2c0/0xfd0 fs/ext4/file.c:264
call_write_iter include/linux/fs.h:1774 [inline]
do_iter_readv_writev+0x41e/0x680 fs/read_write.c:674
do_iter_write fs/read_write.c:953 [inline]
do_iter_write+0x154/0x550 fs/read_write.c:934
vfs_iter_write+0x77/0xb0 fs/read_write.c:966
iter_file_splice_write+0x578/0xae0 fs/splice.c:749
do_splice_from fs/splice.c:851 [inline]
direct_splice_actor+0x126/0x1a0 fs/splice.c:1018
splice_direct_to_actor+0x2a1/0x7b0 fs/splice.c:973
do_splice_direct+0x18d/0x230 fs/splice.c:1061
do_sendfile+0x4db/0xbd0 fs/read_write.c:1440
SYSC_sendfile64 fs/read_write.c:1501 [inline]
SyS_sendfile64+0x102/0x110 fs/read_write.c:1487
do_syscall_64+0x1eb/0x630 arch/x86/entry/common.c:289
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x458c29
RSP: 002b:00007fc820c65c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000458c29
RDX: 0000000000000000 RSI: 0000000000000007 RDI: 0000000000000005
RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 00008000fffffffe R11: 0000000000000246 R12: 00007fc820c666d4
R13: 00000000004c5c9c R14: 00000000004da240 R15: 00000000ffffffff
Code: 74 01 c3 55 48 89 e5 48 8b 75 08 48 8b 88 d0 12 00 00 8b 80 cc 12 00
00 48 8b 11 48 83 c2 01 48 39 d0 76 07 48 89 34 d1 48 89 11 <5d> c3 0f 1f
00 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 41 57
rcu_sched kthread starved for 10552 jiffies! g17001 c17000 f0x0
RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_sched R running task 29776 9 2 0x80000000
Call Trace:
context_switch kernel/sched/core.c:2807 [inline]
__schedule+0x7be/0x1cf0 kernel/sched/core.c:3383
schedule+0x92/0x1c0 kernel/sched/core.c:3427
schedule_timeout+0x43e/0xe10 kernel/time/timer.c:1744
rcu_gp_kthread+0xbf4/0x1ec0 kernel/rcu/tree.c:2255
kthread+0x31c/0x430 kernel/kthread.c:232
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:402
---
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.