bisecting fixing commit since a1b8638ba1320e6684aa98233c15255eb803fac7
building syzkaller on 6bfdbe8950b22b1c832478bd397754f3108df040
testing commit a1b8638ba1320e6684aa98233c15255eb803fac7
compiler: gcc (GCC) 8.4.1 20210217, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: a2bd2a7914d84f11d354c624287775dee6716e67bda7a3dc929d141adfda16a6
run #0: crashed: INFO: task hung in blkdev_put
run #1: crashed: INFO: task hung in blkdev_put
run #2: crashed: INFO: task hung in do_read_cache_page
run #3: crashed: INFO: task hung in do_read_cache_page
run #4: crashed: INFO: task hung in blkdev_put
run #5: crashed: INFO: task hung in do_read_cache_page
run #6: crashed: INFO: task hung in do_read_cache_page
run #7: crashed: INFO: task hung in do_read_cache_page
run #8: crashed: INFO: task hung in do_read_cache_page
run #9: crashed: INFO: task hung in do_read_cache_page
run #10: crashed: INFO: task hung in do_read_cache_page
run #11: crashed: INFO: task hung in do_read_cache_page
run #12: crashed: INFO: task hung in do_read_cache_page
run #13: crashed: INFO: task hung in blkdev_put
run #14: crashed: INFO: task hung in do_read_cache_page
run #15: crashed: INFO: task hung in do_read_cache_page
run #16: crashed: INFO: task hung in blkdev_put
run #17: crashed: INFO: task hung in do_read_cache_page
run #18: crashed: INFO: task hung in do_read_cache_page
run #19: crashed: INFO: task hung in do_read_cache_page
testing current HEAD feb9c5e19e913b53cb536a7aa7c9f20107bb51ec
testing commit feb9c5e19e913b53cb536a7aa7c9f20107bb51ec
compiler: gcc (GCC) 10.2.1 20210217, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 54d390b817a4677486aa0d8c1ed6a30a001cc8464a43fb344b3859a2ace0a095
all runs: crashed: INFO: task hung in do_read_cache_folio
revisions tested: 2, total time: 23m52.086449982s (build: 9m52.530850782s, test: 13m3.142629746s)
the crash still happens on HEAD
commit msg: Merge tag 'for_linus' of git://git.kernel.org/pub/scm/linux/kernel/git/mst/vhost
crash: INFO: task hung in do_read_cache_folio
INFO: task udevd:2708 blocked for more than 143 seconds.
Not tainted 5.18.0-rc6-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:udevd state:D stack:26624 pid: 2708 ppid: 2145 flags:0x00004004
Call Trace:
__schedule+0xd95/0x27f0
schedule+0xd2/0x1f0
io_schedule+0xba/0x130
folio_wait_bit_common+0x387/0x7d0
do_read_cache_folio+0x3c6/0x5c0
read_cache_page+0x48/0x1f0
read_part_sector+0xe0/0x690
adfspart_check_ICS+0x96/0x610
bdev_disk_changed+0x497/0xd20
blkdev_get_whole+0x195/0x270
blkdev_get_by_dev.part.0+0x4cc/0xb20
blkdev_open+0xde/0x240
do_dentry_open+0x414/0xfd0
path_openat+0x9cf/0x2360
do_filp_open+0x199/0x3d0
do_sys_openat2+0x11e/0x3f0
__x64_sys_openat+0x11b/0x1d0
do_syscall_64+0x35/0x80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7ff81e325697
RSP: 002b:00007fff29da78d0 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 0000564d75ee2d00 RCX: 00007ff81e325697
RDX: 00000000000a0800 RSI: 0000564d75ec5110 RDI: 00000000ffffff9c
RBP: 0000564d75ec5110 R08: 0000000000000001 R09: 00007fff29df1080
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000000a0800
R13: 0000564d75ed1d20 R14: 0000000000000001 R15: 0000564d75ea92c0
INFO: task syz-executor.0:2724 blocked for more than 143 seconds.
Not tainted 5.18.0-rc6-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0 state:D stack:27216 pid: 2724 ppid: 2705 flags:0x00000004
Call Trace:
__schedule+0xd95/0x27f0
schedule+0xd2/0x1f0
schedule_preempt_disabled+0xf/0x20
__mutex_lock+0xa32/0x12f0
blkdev_put+0x82/0x830
blkdev_close+0x5f/0x90
__fput+0x1f5/0x8c0
task_work_run+0xc0/0x160
exit_to_user_mode_prepare+0x1e0/0x1f0
syscall_exit_to_user_mode+0x16/0x40
do_syscall_64+0x42/0x80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x418c1b
RSP: 002b:00007ffdbf162dc0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000418c1b
RDX: 00000000000003e7 RSI: 0000001b2cc200bc RDI: 0000000000000004
RBP: 0000000000000005 R08: 0000000000000000 R09: 0000001b2cc200c4
R10: 00007ffdbf1e7090 R11: 0000000000000293 R12: 000000000055d9a0
R13: 000000000055d9a0 R14: 000000000055cf20 R15: 000000000055cf20
INFO: task syz-executor.0:2725 blocked for more than 143 seconds.
Not tainted 5.18.0-rc6-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0 state:D stack:27512 pid: 2725 ppid: 2705 flags:0x00004006
Call Trace:
__schedule+0xd95/0x27f0
schedule+0xd2/0x1f0
schedule_preempt_disabled+0xf/0x20
__mutex_lock+0xa32/0x12f0
blkdev_put+0x82/0x830
blkdev_close+0x5f/0x90
__fput+0x1f5/0x8c0
task_work_run+0xc0/0x160
get_signal+0x19b/0x1ea0
arch_do_signal_or_restart+0x88/0x1a80
exit_to_user_mode_prepare+0x14a/0x1f0
syscall_exit_to_user_mode+0x16/0x40
do_syscall_64+0x42/0x80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x465ad9
RSP: 002b:00007fb1d68e4188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: fffffffffffffe00 RBX: 000000000055cf20 RCX: 0000000000465ad9
RDX: ffffffffffffffff RSI: 000000000000ab03 RDI: 0000000000000003
RBP: 00000000004af756 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000055cf20
R13: 00007ffdbf162d5f R14: 00007fb1d68e4300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by khungtaskd/26:
#0: ffffffff8a1b7d60 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x28c
4 locks held by kworker/0:2/134:
#0: ffff8881f603b358 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x1e/0x30
#1: ffff8881f60287c8 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x176/0x4e0
#2: ffff8881f6029170 (krc.lock){....}-{2:2}, at: kfree_rcu_work+0x32d/0xa30
#3: ffffffff8df22810 (&obj_hash[i].lock){-.-.}-{2:2}, at: debug_check_no_obj_freed+0xc7/0x430
2 locks held by getty/2302:
#0: ffff88817e012098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x1f/0x70
#1: ffffc900016c32e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x9e3/0xed0
1 lock held by udevd/2708:
#0: ffff88810a0b6118 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_get_by_dev.part.0+0x78/0xb20
1 lock held by syz-executor.0/2724:
#0: ffff88810a0b6118 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_put+0x82/0x830
1 lock held by syz-executor.0/2725:
#0: ffff88810a0b6118 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_put+0x82/0x830
=============================================