ci starts bisection 2023-02-21 21:19:48.052784008 +0000 UTC m=+6369.617207611
bisecting fixing commit since a689b938df39ab513026c53fb7011fd7cd594943
building syzkaller on 1dac8c7a01e2bdd35cb04eb4901ddb157291ac2d
ensuring issue is reproducible on original commit a689b938df39ab513026c53fb7011fd7cd594943
testing commit a689b938df39ab513026c53fb7011fd7cd594943 gcc
compiler: Debian clang version 15.0.7, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 10e226eebb842561e66ef1ef24022f9c65c4b838ef6b6ed94d5d84e344adebda
run #0: crashed: INFO: rcu detected stall in corrupted
run #1: crashed: INFO: rcu detected stall in corrupted
run #2: crashed: INFO: rcu detected stall in corrupted
run #3: crashed: INFO: rcu detected stall in corrupted
run #4: crashed: INFO: rcu detected stall in corrupted
run #5: crashed: INFO: rcu detected stall in corrupted
run #6: crashed: INFO: rcu detected stall in corrupted
run #7: crashed: INFO: rcu detected stall in corrupted
run #8: crashed: INFO: rcu detected stall in corrupted
run #9: crashed: INFO: rcu detected stall in corrupted
run #10: crashed: INFO: rcu detected stall in corrupted
run #11: crashed: INFO: rcu detected stall in corrupted
run #12: crashed: INFO: task hung in find_inode_fast
run #13: crashed: INFO: task hung in ext4_evict_ea_inode
run #14: crashed: INFO: task hung in ext4_evict_ea_inode
run #15: crashed: INFO: task hung in find_inode_fast
run #16: crashed: INFO: task hung in find_inode_fast
run #17: crashed: INFO: task hung in ext4_evict_ea_inode
run #18: OK
run #19: OK
testing current HEAD 1b72607d7321e66829e11148712b3a2ba1dc83e7
testing commit 1b72607d7321e66829e11148712b3a2ba1dc83e7 gcc
compiler: Debian clang version 15.0.7, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 5986643cfffd0fbe5fc5fa9177c4995760662b3986d3da490a141c76557c3f0c
run #0: crashed: INFO: rcu detected stall in corrupted
run #1: crashed: INFO: rcu detected stall in corrupted
run #2: crashed: INFO: rcu detected stall in corrupted
run #3: crashed: INFO: rcu detected stall in corrupted
run #4: crashed: INFO: rcu detected stall in corrupted
run #5: crashed: INFO: task hung in find_inode_fast
run #6: crashed: INFO: task hung in find_inode_fast
run #7: crashed: INFO: task hung in ext4_evict_ea_inode
run #8: crashed: INFO: task hung in ext4_evict_ea_inode
run #9: crashed: INFO: task hung in find_inode_fast
revisions tested: 2, total time: 36m22.707905638s (build: 17m43.965258308s, test: 18m7.251246346s)
the crash still happens on HEAD
commit msg: Merge tag 'thermal-6.3-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/rafael/linux-pm
crash: INFO: task hung in find_inode_fast
INFO: task syz-executor.0:13891 blocked for more than 143 seconds.
Not tainted 6.2.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0 state:D stack:23432 pid:13891 ppid:5482 flags:0x00004004
Call Trace:
__schedule+0x17b7/0x4960
schedule+0xc3/0x180
find_inode_fast+0x2e8/0x3d0
iget_locked+0x9b/0x720
__ext4_iget+0x2ef/0x3d80
ext4_xattr_set_entry+0x1308/0x3960
ext4_xattr_block_set+0xbeb/0x3380
ext4_xattr_set_handle+0xabd/0x1280
ext4_xattr_set+0x218/0x370
__vfs_setxattr+0x334/0x380
__vfs_setxattr_noperm+0x106/0x4c0
vfs_setxattr+0x1d3/0x350
setxattr+0x182/0x1f0
path_setxattr+0x17d/0x230
__x64_sys_setxattr+0xb6/0xd0
do_syscall_64+0x41/0xc0
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7f85df08c0c9
RSP: 002b:00007f85dfe92168 EFLAGS: 00000246 ORIG_RAX: 00000000000000bc
RAX: ffffffffffffffda RBX: 00007f85df1abf80 RCX: 00007f85df08c0c9
RDX: 00000000200005c0 RSI: 0000000020000180 RDI: 00000000200000c0
RBP: 00007f85df0e7ae9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000002000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fffc54708bf R14: 00007f85dfe92300 R15: 0000000000022000
INFO: task syz-executor.0:13904 blocked for more than 144 seconds.
Not tainted 6.2.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0 state:D stack:25864 pid:13904 ppid:5482 flags:0x00004004
Call Trace:
__schedule+0x17b7/0x4960
schedule+0xc3/0x180
mb_cache_entry_wait_unused+0x16e/0x200
ext4_evict_ea_inode+0x169/0x2b0
ext4_evict_inode+0x175/0xec0
evict+0x262/0x550
ext4_xattr_set_entry+0x2792/0x3960
ext4_xattr_block_set+0x669/0x3380
ext4_xattr_set_handle+0xabd/0x1280
ext4_xattr_set+0x218/0x370
__vfs_setxattr+0x334/0x380
__vfs_setxattr_noperm+0x106/0x4c0
vfs_setxattr+0x1d3/0x350
setxattr+0x182/0x1f0
path_setxattr+0x17d/0x230
__x64_sys_setxattr+0xb6/0xd0
do_syscall_64+0x41/0xc0
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7f85df08c0c9
RSP: 002b:00007f85dfe71168 EFLAGS: 00000246 ORIG_RAX: 00000000000000bc
RAX: ffffffffffffffda RBX: 00007f85df1ac050 RCX: 00007f85df08c0c9
RDX: 0000000000000000 RSI: 0000000020000200 RDI: 00000000200001c0
RBP: 00007f85df0e7ae9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fffc54708bf R14: 00007f85dfe71300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by rcu_tasks_kthre/12:
#0: ffffffff8bf1a310 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x29/0xd20
1 lock held by rcu_tasks_trace/13:
#0: ffffffff8bf1ab10 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x29/0xd20
2 locks held by kworker/0:1/14:
#0: ffff888011072538 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x6dd/0x1010
#1: ffffc90000137d20 ((work_completion)(&rew->rew_work)){+.+.}-{0:0}, at: process_one_work+0x724/0x1010
1 lock held by khungtaskd/28:
#0:
ffffffff8bf1a140
(rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
2 locks held by kworker/u4:5/1157:
2 locks held by getty/4737:
#0: ffff88802adeb098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x20/0x60
#1:
ffffc900015a02f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x5ed/0x1880
3 locks held by kworker/0:4/5079:
#0: ffff888011070d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x6dd/0x1010
#1: ffffc90003a3fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x724/0x1010
#2: ffffffff8bf1f838 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x3a3/0x890
3 locks held by kworker/0:6/5081:
#0: ffff888011070d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x6dd/0x1010
#1: ffffc90003a5fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x724/0x1010
#2: ffffffff8bf1f838 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x46c/0x890
2 locks held by kworker/1:4/5485:
#0: ffff888011070d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x6dd/0x1010
#1: ffffc9000460fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x724/0x1010
2 locks held by kworker/1:7/5603:
#0: ffff888011070d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x6dd/0x1010
#1: ffffc90004c2fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x724/0x1010
2 locks held by kworker/1:8/5669:
#0: ffff888011070d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x6dd/0x1010
#1: ffffc90004c8fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x724/0x1010
3 locks held by syz-executor.0/13891:
#0: ffff888027d98460 (sb_writers#4){.+.+}-{0:0}, at: mnt_want_write+0x3a/0x70
#1: ffff8880710c3628 (&sb->s_type->i_mutex_key#7){++++}-{3:3}, at: vfs_setxattr+0x192/0x350
#2: ffff8880710c32f0 (&ei->xattr_sem){++++}-{3:3}, at: ext4_xattr_set_handle+0x23b/0x1280
3 locks held by syz-executor.0/13904:
#0: ffff888027d98460 (sb_writers#4){.+.+}-{0:0}, at: mnt_want_write+0x3a/0x70
#1: ffff888071088e08 (&type->i_mutex_dir_key#3){++++}-{3:3}, at: vfs_setxattr+0x192/0x350
#2: ffff888071088ad0 (&ei->xattr_sem){++++}-{3:3}, at: ext4_xattr_set_handle+0x23b/0x1280
3 locks held by udevd/13963:
#0: ffff8880b9d3b1d8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140
#1: ffff8880b9d3b1d8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140
#2: ffff88807d9cdab0 (&mm->cid_lock#2){....}-{2:2}, at: __schedule+0x1340/0x4960
2 locks held by syz-executor.0/18788:
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 28 Comm: khungtaskd Not tainted 6.2.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023
Call Trace:
dump_stack_lvl+0x167/0x220
nmi_cpu_backtrace+0x381/0x3f0
nmi_trigger_cpumask_backtrace+0x138/0x310
watchdog+0xb5f/0xba0
kthread+0x232/0x2b0
ret_from_fork+0x1f/0x30
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 15 Comm: ksoftirqd/0 Not tainted 6.2.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023
RIP: 0010:lock_is_held_type+0x57/0x190
Code: 84 00 01 00 00 65 8b 05 ff 5b 5f 76 85 c0 0f 85 f1 00 00 00 65 4c 8b 2d 37 7b 60 76 41 83 bd 34 0a 00 00 00 0f 85 db 00 00 00 <41> 89 f6 49 89 ff 48 c7 04 24 00 00 00 00 9c 8f 04 24 4c 8b 24 24
RSP: 0018:ffffc90000147958 EFLAGS: 00000046
RAX: 0000000000000000 RBX: 1ffff92000028f34 RCX: 0000000080000102
RDX: 0000000000000000 RSI: 00000000ffffffff RDI: ffffffff8bf1a0c0
RBP: 00000000ffffffff R08: dffffc0000000000 R09: fffffbfff1a79646
R10: 0000000000000000 R11: dffffc0000000001 R12: ffff88806cebb400
R13: ffff8880113e3a00 R14: dffffc0000000000 R15: 0000000000000000
FS: 0000000000000000(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f4f6183e000 CR3: 000000000bd27000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
rcu_read_lock_sched_held+0x8d/0x130
__update_load_avg_cfs_rq+0x712/0xc70
update_blocked_averages+0xb57/0x16a0
run_rebalance_domains+0xf9/0x1b0
__do_softirq+0x311/0xb1a
run_ksoftirqd+0xc5/0x120
smpboot_thread_fn+0x534/0x8e0
kthread+0x232/0x2b0
ret_from_fork+0x1f/0x30