ci2 starts bisection 2023-06-18 19:47:07.382310409 +0000 UTC m=+252650.575107158
bisecting fixing commit since 9d6bde853685609a631871d7c12be94fdf8d912e
building syzkaller on 3bb7af1def6b7b99e4c1c9573162eb41b5893cd3
ensuring issue is reproducible on original commit 9d6bde853685609a631871d7c12be94fdf8d912e

testing commit 9d6bde853685609a631871d7c12be94fdf8d912e gcc
compiler: gcc (GCC) 10.2.1 20210217, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: e5103f778a1232cba0f5b4fe5daf82f641016dae735b65b1ced95c5ca93c1a77
all runs: crashed: INFO: task hung in f2fs_balance_fs
testing current HEAD 471e639e59d128f4bf58000a118b2ceca3893f98
testing commit 471e639e59d128f4bf58000a118b2ceca3893f98 gcc
compiler: gcc (GCC) 10.2.1 20210217, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 34c6f061a43a184998bf5a9a52ae40377cebbf655a12b2afe77a05e87b7c3fa4
all runs: crashed: INFO: task hung in f2fs_balance_fs
crash still not fixed/happens on the oldest tested release
revisions tested: 2, total time: 35m1.978376692s (build: 22m12.040043874s, test: 12m13.83332981s)
crash still not fixed on HEAD or HEAD had kernel test errors
commit msg: Linux 5.15.117
crash: INFO: task hung in f2fs_balance_fs
INFO: task kworker/u4:1:144 blocked for more than 143 seconds.
      Not tainted 5.15.117-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/u4:1    state:D stack:24168 pid:  144 ppid:     2 flags:0x00004000
Workqueue: writeback wb_workfn (flush-7:0)
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5026 [inline]
 __schedule+0xaf0/0x54c0 kernel/sched/core.c:6372
 schedule+0x10a/0x240 kernel/sched/core.c:6455
 rwsem_down_write_slowpath+0x6a5/0x1230 kernel/locking/rwsem.c:1157
 __down_write_common kernel/locking/rwsem.c:1284 [inline]
 __down_write_common kernel/locking/rwsem.c:1281 [inline]
 __down_write kernel/locking/rwsem.c:1293 [inline]
 down_write+0x137/0x150 kernel/locking/rwsem.c:1542
 f2fs_balance_fs+0x43c/0x5a0 fs/f2fs/segment.c:529
 f2fs_write_inode+0x46c/0xd00 fs/f2fs/inode.c:727
 write_inode fs/fs-writeback.c:1478 [inline]
 __writeback_single_inode+0x668/0x930 fs/fs-writeback.c:1683
 writeback_sb_inodes+0x466/0xc00 fs/fs-writeback.c:1908
 __writeback_inodes_wb+0xb2/0x200 fs/fs-writeback.c:1979
 wb_writeback+0x5a6/0x7a0 fs/fs-writeback.c:2084
 wb_check_old_data_flush fs/fs-writeback.c:2184 [inline]
 wb_do_writeback fs/fs-writeback.c:2237 [inline]
 wb_workfn+0x691/0xe30 fs/fs-writeback.c:2266
 process_one_work+0x85b/0x1360 kernel/workqueue.c:2307
 worker_thread+0x5a6/0xf90 kernel/workqueue.c:2454
 kthread+0x38b/0x460 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:298
 </TASK>

Showing all locks held in the system:
1 lock held by khungtaskd/27:
 #0: ffffffff8b17c8c0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x272 kernel/locking/lockdep.c:6451
4 locks held by kworker/u4:1/144:
 #0: ffff8880137ec938 ((wq_completion)writeback){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: ffff8880137ec938 ((wq_completion)writeback){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
 #0: ffff8880137ec938 ((wq_completion)writeback){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1198 [inline]
 #0: ffff8880137ec938 ((wq_completion)writeback){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:635 [inline]
 #0: ffff8880137ec938 ((wq_completion)writeback){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:662 [inline]
 #0: ffff8880137ec938 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x780/0x1360 kernel/workqueue.c:2278
 #1: ffffc900010afdb8 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x7ad/0x1360 kernel/workqueue.c:2282
 #2: ffff888020e940e0 (&type->s_umount_key#51){.+.+}-{3:3}, at: trylock_super+0x11/0xb0 fs/super.c:418
 #3: ffff88801a681108 (&sbi->gc_lock){+.+.}-{3:3}, at: f2fs_balance_fs+0x43c/0x5a0 fs/f2fs/segment.c:529
2 locks held by getty/3263:
 #0: ffff88814aae5098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x1f/0x70 drivers/tty/tty_ldisc.c:252
 #1: ffffc900027b32e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xb1a/0x1040 drivers/tty/n_tty.c:2147
3 locks held by syz-executor.0/4050:
3 locks held by syz-executor.0/4663:

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 27 Comm: khungtaskd Not tainted 5.15.117-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/27/2023
Call Trace:
 <TASK>
 __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+0x18f/0x1d0 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:148 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
 watchdog+0x8a6/0xba0 kernel/hung_task.c:295
 kthread+0x38b/0x460 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:298
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 4050 Comm: syz-executor.0 Not tainted 5.15.117-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/27/2023
RIP: 0010:kasan_check_range+0x16/0x180 mm/kasan/generic.c:188
Code: 02 0f 1f 00 48 89 f2 be f8 00 00 00 e9 53 a7 01 02 0f 1f 00 48 85 f6 0f 84 70 01 00 00 49 89 f9 41 54 44 0f b6 c2 49 01 f1 55 <53> 0f 82 18 01 00 00 48 b8 ff ff ff ff ff 7f ff ff 48 39 c7 0f 86
RSP: 0018:ffffc9000296e4f0 EFLAGS: 00000282
RAX: 1ffff1100df9cd8c RBX: ffff88806fce6c58 RCX: ffffffff88b8ed9d
RDX: 0000000000000001 RSI: 0000000000000008 RDI: ffff88806fce6c60
RBP: 0000000000000001 R08: 0000000000000001 R09: ffff88806fce6c68
R10: ffffed100df9cd8c R11: 0000000000000001 R12: 1ffff9200052dca2
R13: dffffc0000000000 R14: ffff888068118000 R15: ffff88806fce6c60
FS:  00007fafd5977700(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000055c668b48000 CR3: 0000000072188000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <TASK>
 instrument_atomic_write include/linux/instrumented.h:86 [inline]
 atomic_long_set include/linux/atomic/atomic-instrumented.h:1197 [inline]
 __rwsem_set_reader_owned kernel/locking/rwsem.c:171 [inline]
 rwsem_set_reader_owned kernel/locking/rwsem.c:176 [inline]
 rwsem_read_trylock kernel/locking/rwsem.c:244 [inline]
 rwsem_read_trylock kernel/locking/rwsem.c:236 [inline]
 __down_read_common kernel/locking/rwsem.c:1230 [inline]
 __down_read kernel/locking/rwsem.c:1244 [inline]
 down_read+0x1bd/0x470 kernel/locking/rwsem.c:1490
 check_valid_map fs/f2fs/gc.c:881 [inline]
 gc_data_segment fs/f2fs/gc.c:1450 [inline]
 do_garbage_collect+0x152e/0x36c0 fs/f2fs/gc.c:1670
 f2fs_gc+0x69a/0x12c0 fs/f2fs/gc.c:1764
 f2fs_balance_fs+0x450/0x5a0 fs/f2fs/segment.c:530
 f2fs_write_single_data_page+0x1017/0x1490 fs/f2fs/data.c:2869
 f2fs_write_cache_pages+0x7f6/0x1600 fs/f2fs/data.c:3083
 __f2fs_write_data_pages fs/f2fs/data.c:3234 [inline]
 f2fs_write_data_pages+0x412/0xeb0 fs/f2fs/data.c:3261
 do_writepages+0x16c/0x6e0 mm/page-writeback.c:2364
 filemap_fdatawrite_wbc mm/filemap.c:400 [inline]
 filemap_fdatawrite_wbc+0x192/0x2a0 mm/filemap.c:390
 __filemap_fdatawrite_range mm/filemap.c:433 [inline]
 file_write_and_wait_range+0x142/0x1a0 mm/filemap.c:810
 f2fs_do_sync_file+0x2ef/0x1ae0 fs/f2fs/file.c:274
 generic_write_sync include/linux/fs.h:2913 [inline]
 f2fs_file_write_iter+0x43e/0xa40 fs/f2fs/file.c:4358
 __kernel_write+0x469/0x9c0 fs/read_write.c:539
 __dump_emit+0x151/0x260 fs/coredump.c:875
 dump_emit fs/coredump.c:912 [inline]
 dump_user_range+0xbb/0x2b0 fs/coredump.c:949
 elf_core_dump+0x22f1/0x2f30 fs/binfmt_elf.c:2285
 do_coredump+0x1fcd/0x3960 fs/coredump.c:826
 get_signal+0xd19/0x2340 kernel/signal.c:2875
 arch_do_signal_or_restart+0x2a9/0x1c20 arch/x86/kernel/signal.c:865
 handle_signal_work kernel/entry/common.c:148 [inline]
 exit_to_user_mode_loop kernel/entry/common.c:172 [inline]
 exit_to_user_mode_prepare+0x1cf/0x260 kernel/entry/common.c:208
 irqentry_exit_to_user_mode+0x5/0x30 kernel/entry/common.c:314
 asm_exc_page_fault+0x22/0x30 arch/x86/include/asm/idtentry.h:568
RIP: 0033:0x0
Code: Unable to access opcode bytes at RIP 0xffffffffffffffd6.
RSP: 002b:00000000200001c8 EFLAGS: 00010217
RAX: 0000000000000000 RBX: 00007fafdab46050 RCX: 00007fafdaa26169
RDX: 0000000000000000 RSI: 00000000200001c0 RDI: 0000000080004000
RBP: 00007fafdaa81ca1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000000
R13: 00007ffd3e025f5f R14: 00007fafd5977300 R15: 0000000000022000
 </TASK>