bisecting fixing commit since 7fd2bf83d59a2d32e0d596c5d3e623b9a0e7e2d5
building syzkaller on 838e7e2cd9228583ca33c49a39aea4d863d3e36d
testing commit 7fd2bf83d59a2d32e0d596c5d3e623b9a0e7e2d5
compiler: gcc (GCC) 10.2.1 20210217, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 1ea94d1a92e7d49dc3d6a449c0bbea04cb19022e1787c51b44fd2f33eba59b87
all runs: crashed: INFO: task hung in fuse_simple_request
testing current HEAD 2293be58d6a18cab800e25e42081bacb75c05752
testing commit 2293be58d6a18cab800e25e42081bacb75c05752
compiler: gcc (GCC) 10.2.1 20210217, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 4b565791c6bb6c89549c9197edb2e879b5cb45785337aa6f8df0d85f7e8a1979
all runs: crashed: INFO: task hung in fuse_simple_request
revisions tested: 2, total time: 25m45.037690657s (build: 13m2.109050332s, test: 11m50.792398387s)
the crash still happens on HEAD
commit msg: Merge tag 'trace-v5.17-rc4' of git://git.kernel.org/pub/scm/linux/kernel/git/rostedt/linux-trace
crash: INFO: task hung in fuse_simple_request
INFO: task syz-executor.5:12227 blocked for more than 143 seconds.
Not tainted 5.17.0-rc5-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D stack:27424 pid:12227 ppid: 3938 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4995 [inline]
__schedule+0x90d/0x2680 kernel/sched/core.c:6304
schedule+0xd2/0x260 kernel/sched/core.c:6377
request_wait_answer+0x395/0x6b0 fs/fuse/dev.c:411
__fuse_request_send fs/fuse/dev.c:430 [inline]
fuse_simple_request+0x405/0xad0 fs/fuse/dev.c:515
fuse_access+0x1bc/0x320 fs/fuse/dir.c:1261
do_inode_permission fs/namei.c:457 [inline]
inode_permission.part.0+0x2ca/0x470 fs/namei.c:524
path_permission include/linux/fs.h:2917 [inline]
__do_sys_chdir fs/open.c:494 [inline]
__se_sys_chdir fs/open.c:484 [inline]
__x64_sys_chdir+0xf6/0x1f0 fs/open.c:484
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7fecb61ec8d9
RSP: 002b:00007fecb5963188 EFLAGS: 00000246 ORIG_RAX: 0000000000000050
RAX: ffffffffffffffda RBX: 00007fecb62f0f60 RCX: 00007fecb61ec8d9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00000000200020c0
RBP: 00007fecb6246cb4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fecb6824b2f R14: 00007fecb5963300 R15: 0000000000022000
INFO: task syz-executor.5:12456 blocked for more than 145 seconds.
Not tainted 5.17.0-rc5-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D stack:27752 pid:12456 ppid: 3938 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4995 [inline]
__schedule+0x90d/0x2680 kernel/sched/core.c:6304
schedule+0xd2/0x260 kernel/sched/core.c:6377
request_wait_answer+0x395/0x6b0 fs/fuse/dev.c:411
__fuse_request_send fs/fuse/dev.c:430 [inline]
fuse_simple_request+0x405/0xad0 fs/fuse/dev.c:515
fuse_access+0x1bc/0x320 fs/fuse/dir.c:1261
do_inode_permission fs/namei.c:457 [inline]
inode_permission.part.0+0x2ca/0x470 fs/namei.c:524
path_permission include/linux/fs.h:2917 [inline]
__do_sys_chdir fs/open.c:494 [inline]
__se_sys_chdir fs/open.c:484 [inline]
__x64_sys_chdir+0xf6/0x1f0 fs/open.c:484
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7fecb61ec8d9
RSP: 002b:00007fecb5963188 EFLAGS: 00000246 ORIG_RAX: 0000000000000050
RAX: ffffffffffffffda RBX: 00007fecb62f0f60 RCX: 00007fecb61ec8d9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00000000200020c0
RBP: 00007fecb6246cb4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fecb6824b2f R14: 00007fecb5963300 R15: 0000000000022000
INFO: task syz-executor.5:12876 blocked for more than 146 seconds.
Not tainted 5.17.0-rc5-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D stack:27432 pid:12876 ppid: 3938 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4995 [inline]
__schedule+0x90d/0x2680 kernel/sched/core.c:6304
schedule+0xd2/0x260 kernel/sched/core.c:6377
request_wait_answer+0x395/0x6b0 fs/fuse/dev.c:411
__fuse_request_send fs/fuse/dev.c:430 [inline]
fuse_simple_request+0x405/0xad0 fs/fuse/dev.c:515
fuse_access+0x1bc/0x320 fs/fuse/dir.c:1261
do_inode_permission fs/namei.c:457 [inline]
inode_permission.part.0+0x2ca/0x470 fs/namei.c:524
path_permission include/linux/fs.h:2917 [inline]
__do_sys_chdir fs/open.c:494 [inline]
__se_sys_chdir fs/open.c:484 [inline]
__x64_sys_chdir+0xf6/0x1f0 fs/open.c:484
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7fecb61ec8d9
RSP: 002b:00007fecb5963188 EFLAGS: 00000246 ORIG_RAX: 0000000000000050
RAX: ffffffffffffffda RBX: 00007fecb62f0f60 RCX: 00007fecb61ec8d9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00000000200020c0
RBP: 00007fecb6246cb4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fecb6824b2f R14: 00007fecb5963300 R15: 0000000000022000
Showing all locks held in the system:
2 locks held by kworker/u4:0/8:
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:631 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:658 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x79e/0x1410 kernel/workqueue.c:2278
#1: ffffc90000cd7dc0 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work+0x7cb/0x1410 kernel/workqueue.c:2282
1 lock held by khungtaskd/27:
#0: ffffffff8ad78d20 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6460
2 locks held by kworker/u4:2/44:
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:631 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:658 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x79e/0x1410 kernel/workqueue.c:2278
#1: ffffc9000114fdc0 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work+0x7cb/0x1410 kernel/workqueue.c:2282
2 locks held by kworker/u4:3/90:
2 locks held by getty/3311:
#0: ffff88807eca8098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x1f/0x70 drivers/tty/tty_ldisc.c:244
#1: ffffc900027662e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x9dd/0xed0 drivers/tty/n_tty.c:2075
1 lock held by syz-executor.5/3938:
#0: ffff88814b3a0d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0xf1/0x500 net/netfilter/x_tables.c:1242
2 locks held by syz-executor.4/3967:
1 lock held by syz-executor.3/4270:
2 locks held by kworker/u4:8/4314:
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:631 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:658 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x79e/0x1410 kernel/workqueue.c:2278
#1: ffffc9000310fdc0 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work+0x7cb/0x1410 kernel/workqueue.c:2282
2 locks held by kworker/u4:9/4326:
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:631 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:658 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x79e/0x1410 kernel/workqueue.c:2278
#1: ffff8880b9e27948 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x3e7/0x4e0 kernel/sched/psi.c:891
2 locks held by kworker/u4:10/4333:
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:631 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:658 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x79e/0x1410 kernel/workqueue.c:2278
#1: ffffc900030dfdc0 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work+0x7cb/0x1410 kernel/workqueue.c:2282
2 locks held by kworker/u4:13/4354:
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:631 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:658 [inline]
#0: ffff88800fc69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x79e/0x1410 kernel/workqueue.c:2278
#1: ffffc9000326fdc0 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work+0x7cb/0x1410 kernel/workqueue.c:2282
2 locks held by modprobe/12246:
#0: ffff8880b9e39c18 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:489 [inline]
#0: ffff8880b9e39c18 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock kernel/sched/sched.h:1318 [inline]
#0: ffff8880b9e39c18 (&rq->__lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1616 [inline]
#0: ffff8880b9e39c18 (&rq->__lock){-.-.}-{2:2}, at: __schedule+0x236/0x2680 kernel/sched/core.c:6218
#1: ffff8880b9e27948 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x3e7/0x4e0 kernel/sched/psi.c:891
1 lock held by modprobe/12929:
1 lock held by modprobe/12932:
2 locks held by modprobe/12942:
1 lock held by modprobe/12943:
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: khungtaskd Not tainted 5.17.0-rc5-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0x57/0x7d lib/dump_stack.c:106
nmi_cpu_backtrace.cold+0x30/0xc0 lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x140/0x170 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:212 [inline]
watchdog+0x88c/0xbf0 kernel/hung_task.c:369
kthread+0x299/0x340 kernel/kthread.c:377
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 12924 Comm: syz-executor.4 Not tainted 5.17.0-rc5-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:lookup_chain_cache kernel/locking/lockdep.c:3695 [inline]
RIP: 0010:lookup_chain_cache_add kernel/locking/lockdep.c:3714 [inline]
RIP: 0010:validate_chain kernel/locking/lockdep.c:3769 [inline]
RIP: 0010:__lock_acquire+0x1674/0x5630 kernel/locking/lockdep.c:5027
Code: 5d 48 ba 00 00 00 00 00 fc ff df 48 89 e9 48 8b 6c 24 08 eb 06 48 83 eb 08 74 40 48 8d 7b 18 48 89 f8 48 c1 e8 03 80 3c 10 00 <0f> 85 47 37 00 00 48 8b 43 18 48 39 c5 0f 84 49 f6 ff ff 48 8d 7b
RSP: 0018:ffffc90002a5f868 EFLAGS: 00000046
RAX: 1ffffffff1c99d1f RBX: ffffffff8e4ce8e0 RCX: 0000000000002534
RDX: dffffc0000000000 RSI: 0000000000000008 RDI: ffffffff8e4ce8f8
RBP: 1dbd679bf89e6085 R08: 0000000000000000 R09: ffffffff8f0c7887
R10: fffffbfff1e18f10 R11: 0000000000000001 R12: ffff888044cba730
R13: ffff888044cb9cc0 R14: ffffffff8ca506c8 R15: 0000000000000000
FS: 0000000000000000(0000) GS:ffff8880b9f00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fc1ad9edf28 CR3: 0000000074c1d000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
lock_acquire kernel/locking/lockdep.c:5639 [inline]
lock_acquire+0x1ab/0x510 kernel/locking/lockdep.c:5604
__raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
_raw_spin_lock_irqsave+0x39/0x50 kernel/locking/spinlock.c:162
__debug_check_no_obj_freed lib/debugobjects.c:980 [inline]
debug_check_no_obj_freed+0xc7/0x420 lib/debugobjects.c:1023
slab_free_hook mm/slub.c:1703 [inline]
slab_free_freelist_hook+0xeb/0x1c0 mm/slub.c:1754
slab_free mm/slub.c:3509 [inline]
kmem_cache_free+0xd7/0x370 mm/slub.c:3526
anon_vma_chain_free mm/rmap.c:142 [inline]
unlink_anon_vmas+0x149/0x7d0 mm/rmap.c:415
free_pgtables+0x131/0x2b0 mm/memory.c:427
exit_mmap+0x1c6/0x5b0 mm/mmap.c:3179
__mmput+0xed/0x430 kernel/fork.c:1114
exit_mm kernel/exit.c:507 [inline]
do_exit+0x909/0x2500 kernel/exit.c:793
do_group_exit+0xb2/0x2a0 kernel/exit.c:935
__do_sys_exit_group kernel/exit.c:946 [inline]
__se_sys_exit_group kernel/exit.c:944 [inline]
__x64_sys_exit_group+0x35/0x40 kernel/exit.c:944
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7fc1ad3b88d9
Code: Unable to access opcode bytes at RIP 0x7fc1ad3b88af.
RSP: 002b:00007fc1ad9f0a98 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
RAX: ffffffffffffffda RBX: 00007fc1ad4bd02c RCX: 00007fc1ad3b88d9
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: 00007fc1ad411f8a R08: 000000000000000c R09: 00007fc1ad4bcf60
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000000000b
R13: 00007fc1ad9f0cb0 R14: 00007fc1ad4bcf60 R15: 0000000000000001
----------------
Code disassembly (best guess):
0: 5d pop %rbp
1: 48 ba 00 00 00 00 00 movabs $0xdffffc0000000000,%rdx
8: fc ff df
b: 48 89 e9 mov %rbp,%rcx
e: 48 8b 6c 24 08 mov 0x8(%rsp),%rbp
13: eb 06 jmp 0x1b
15: 48 83 eb 08 sub $0x8,%rbx
19: 74 40 je 0x5b
1b: 48 8d 7b 18 lea 0x18(%rbx),%rdi
1f: 48 89 f8 mov %rdi,%rax
22: 48 c1 e8 03 shr $0x3,%rax
26: 80 3c 10 00 cmpb $0x0,(%rax,%rdx,1)
* 2a: 0f 85 47 37 00 00 jne 0x3777 <-- trapping instruction
30: 48 8b 43 18 mov 0x18(%rbx),%rax
34: 48 39 c5 cmp %rax,%rbp
37: 0f 84 49 f6 ff ff je 0xfffff686
3d: 48 rex.W
3e: 8d .byte 0x8d
3f: 7b .byte 0x7b