btrfs/079 hang (6.13 for-next)

[kdave]

Run full series, 50% to hit that.

PLATFORM      -- Linux/x86_64 (none) 6.12.0-rc6-default+ #482 SMP PREEMPT_DYNAMIC Thu Nov  7 21:05:51 CET 2024
MKFS_OPTIONS  -- -K -f /dev/vdb
MOUNT_OPTIONS -- /dev/vdb /tmp/scratch

btrfs/079        [14:52:02][ 1038.185948] run fstests btrfs/079 at 2024-11-08 14:52:03
[ 1038.387666] BTRFS: device fsid ceed0b43-d9a3-4d8e-ac57-5f5276e28c89 devid 1 transid 101 /dev/vda (254:0) scanned by mount (17800)
[ 1038.389190] BTRFS info (device vda): first mount of filesystem ceed0b43-d9a3-4d8e-ac57-5f5276e28c89
[ 1038.389901] BTRFS info (device vda): using crc32c (crc32c-generic) checksum algorithm
[ 1038.390495] BTRFS info (device vda): using free-space-tree
[ 1038.511555] BTRFS: device fsid 8ac96f25-7dd1-40a6-9881-7e7cbf585934 devid 1 transid 8 /dev/vdb (254:16) scanned by mount (17871)
[ 1038.512867] BTRFS info (device vdb): first mount of filesystem 8ac96f25-7dd1-40a6-9881-7e7cbf585934
[ 1038.513567] BTRFS info (device vdb): using crc32c (crc32c-generic) checksum algorithm
[ 1038.514168] BTRFS info (device vdb): using free-space-tree
[ 1038.517898] BTRFS info (device vdb): checking UUID tree
[ 1315.372866] kworker/u32:4 (22706) used greatest stack depth: 10272 bytes left
[ 1330.765084] kworker/u32:10 (31719) used greatest stack depth: 10240 bytes left
[ 1330.766020] kworker/u32:12 (18260) used greatest stack depth: 10032 bytes left
[ 1971.476884] INFO: task kworker/u32:7:31716 blocked for more than 491 seconds.
[ 1971.477659]       Not tainted 6.12.0-rc6-default+ #482
[ 1971.478104] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1971.478692] task:kworker/u32:7   state:D stack:10544 pid:31716 tgid:31716 ppid:2      flags:0x00004000
[ 1971.479402] Workqueue: btrfs-endio-write btrfs_work_helper [btrfs]
[ 1971.480043] Call Trace:
[ 1971.480291]  <TASK>
[ 1971.480461]  __schedule+0x2f9/0x760
[ 1971.480698]  schedule+0x35/0xe0
[ 1971.481015]  wait_extent_bit+0x248/0x290 [btrfs]
[ 1971.481431]  ? swake_up_locked+0x50/0x50
[ 1971.481762]  __lock_extent+0x94/0xe0 [btrfs]
[ 1971.482154]  btrfs_finish_one_ordered+0x3b0/0x7c0 [btrfs]
[ 1971.482609]  ? lock_acquire+0xb4/0x2f0
[ 1971.482924]  ? process_one_work+0x1e1/0x5b0
[ 1971.483262]  btrfs_work_helper+0xf6/0x270 [btrfs]
[ 1971.483669]  process_one_work+0x222/0x5b0
[ 1971.483999]  worker_thread+0x1d3/0x380
[ 1971.484429]  ? rescuer_thread+0x3e0/0x3e0
[ 1971.484901]  kthread+0xca/0x100
[ 1971.485256]  ? kthread_park+0x80/0x80
[ 1971.485678]  ret_from_fork+0x28/0x40
[ 1971.486055]  ? kthread_park+0x80/0x80
[ 1971.486450]  ret_from_fork_asm+0x11/0x20
[ 1971.486905]  </TASK>
[ 1971.487241] INFO: task kworker/u32:17:12530 blocked for more than 491 seconds.
[ 1971.487892]       Not tainted 6.12.0-rc6-default+ #482
[ 1971.488297] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1971.488898] task:kworker/u32:17  state:D stack:10664 pid:12530 tgid:12530 ppid:2      flags:0x00004000
[ 1971.489604] Workqueue: writeback wb_workfn (flush-btrfs-493)
[ 1971.490049] Call Trace:
[ 1971.490279]  <TASK>
[ 1971.490487]  __schedule+0x2f9/0x760
[ 1971.490783]  schedule+0x35/0xe0
[ 1971.491057]  wait_extent_bit+0x248/0x290 [btrfs]
[ 1971.491468]  ? swake_up_locked+0x50/0x50
[ 1971.491794]  __lock_extent+0x94/0xe0 [btrfs]
[ 1971.492183]  find_lock_delalloc_range+0x1c0/0x2b0 [btrfs]
[ 1971.492639]  writepage_delalloc+0x14b/0x3c0 [btrfs]
[ 1971.493065]  extent_writepage+0xd8/0x320 [btrfs]
[ 1971.493497]  extent_write_cache_pages+0x1bd/0x4e0 [btrfs]
[ 1971.493947]  btrfs_writepages+0x6d/0x120 [btrfs]
[ 1971.494342]  ? end_bbio_meta_write+0x460/0x460 [btrfs]
[ 1971.494767]  do_writepages+0x6f/0x280
[ 1971.495083]  ? writeback_sb_inodes+0x161/0x580
[ 1971.495444]  ? local_clock_noinstr+0x9/0xb0
[ 1971.495790]  __writeback_single_inode+0x56/0x380
[ 1971.496158]  writeback_sb_inodes+0x222/0x580
[ 1971.496507]  __writeback_inodes_wb+0x43/0xf0
[ 1971.496864]  wb_writeback+0x1ae/0x440
[ 1971.497173]  wb_do_writeback+0x2b8/0x2d0
[ 1971.497501]  wb_workfn+0x53/0x1f0
[ 1971.497789]  process_one_work+0x222/0x5b0
[ 1971.498120]  ? process_one_work+0x252/0x5b0
[ 1971.498463]  worker_thread+0x1d3/0x380
[ 1971.498777]  ? rescuer_thread+0x3e0/0x3e0
[ 1971.499114]  kthread+0xca/0x100
[ 1971.499392]  ? kthread_park+0x80/0x80
[ 1971.499702]  ret_from_fork+0x28/0x40
[ 1971.500007]  ? kthread_park+0x80/0x80
[ 1971.500316]  ret_from_fork_asm+0x11/0x20
[ 1971.500640]  </TASK>
[ 1971.500867] INFO: task btrfs-transacti:17886 blocked for more than 491 seconds.
[ 1971.501428]       Not tainted 6.12.0-rc6-default+ #482
[ 1971.501829] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1971.502430] task:btrfs-transacti state:D stack:13808 pid:17886 tgid:17886 ppid:2      flags:0x00004000
[ 1971.503128] Call Trace:
[ 1971.503362]  <TASK>
[ 1971.503572]  __schedule+0x2f9/0x760
[ 1971.503874]  schedule+0x35/0xe0
[ 1971.504153]  btrfs_commit_transaction+0x372/0xf80 [btrfs]
[ 1971.504602]  ? start_transaction+0xbb/0x8d0 [btrfs]
[ 1971.505019]  ? swake_up_locked+0x50/0x50
[ 1971.505345]  transaction_kthread+0x151/0x1b0 [btrfs]
[ 1971.505764]  ? btrfs_cleanup_transaction.isra.0+0x460/0x460 [btrfs]
[ 1971.506259]  kthread+0xca/0x100
[ 1971.506532]  ? kthread_park+0x80/0x80
[ 1971.506841]  ret_from_fork+0x28/0x40
[ 1971.507145]  ? kthread_park+0x80/0x80
[ 1971.507450]  ret_from_fork_asm+0x11/0x20
[ 1971.507777]  </TASK>
[ 1971.507993] INFO: task dd:17899 blocked for more than 491 seconds.
[ 1971.508463]       Not tainted 6.12.0-rc6-default+ #482
[ 1971.508871] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1971.509480] task:dd              state:D stack:12640 pid:17899 tgid:17899 ppid:17894  flags:0x00000000
[ 1971.510175] Call Trace:
[ 1971.510434]  <TASK>
[ 1971.510664]  __schedule+0x2f9/0x760
[ 1971.510963]  schedule+0x35/0xe0
[ 1971.511241]  schedule_preempt_disabled+0x10/0x20
[ 1971.511609]  rwsem_down_write_slowpath+0x1fa/0x620
[ 1971.511985]  ? btrfs_inode_lock+0x33/0x60 [btrfs]
[ 1971.512387]  down_write+0x1ea/0x200
[ 1971.512687]  btrfs_inode_lock+0x33/0x60 [btrfs]
[ 1971.513097]  btrfs_buffered_write+0xa2/0x7a0 [btrfs]
[ 1971.513515]  ? __might_fault+0x2f/0x70
[ 1971.513840]  btrfs_do_write_iter+0x17e/0x1e0 [btrfs]
[ 1971.514272]  ? lock_is_held_type+0x83/0xf0
[ 1971.514609]  vfs_write+0x2c2/0x5b0
[ 1971.514904]  ksys_write+0x72/0xf0
[ 1971.515195]  do_syscall_64+0x54/0xf0
[ 1971.515501]  entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 1971.515913] RIP: 0033:0x7f867d6daea4
[ 1971.516216] RSP: 002b:00007ffc07484498 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
[ 1971.516806] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f867d6daea4
[ 1971.517338] RDX: 0000000000100000 RSI: 00007f867d4c7000 RDI: 0000000000000001
[ 1971.517862] RBP: 0000000000100000 R08: 00007f867d823480 R09: 0000000000000000
[ 1971.518393] R10: 0000000000000022 R11: 0000000000000202 R12: 00007f867d4c7000
[ 1971.518922] R13: 0000000000000200 R14: 0000000000100000 R15: 0000000000000000
[ 1971.519456]  </TASK>
[ 1971.519674] INFO: task filefrag:978 blocked for more than 491 seconds.
[ 1971.520167]       Not tainted 6.12.0-rc6-default+ #482
[ 1971.520569] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1971.521177] task:filefrag        state:D stack:12608 pid:978   tgid:978   ppid:17895  flags:0x00004000
[ 1971.521876] Call Trace:
[ 1971.522105]  <TASK>
[ 1971.522320]  __schedule+0x2f9/0x760
[ 1971.522623]  ? wait_current_trans+0xae/0x1a0 [btrfs]
[ 1971.523041]  schedule+0x35/0xe0
[ 1971.523317]  wait_current_trans+0x131/0x1a0 [btrfs]
[ 1971.523730]  ? swake_up_locked+0x50/0x50
[ 1971.524056]  start_transaction+0x410/0x8d0 [btrfs]
[ 1971.524460]  btrfs_is_data_extent_shared+0xf2/0x670 [btrfs]
[ 1971.524934]  ? mark_held_locks+0x40/0x70
[ 1971.525263]  extent_fiemap+0x63e/0x950 [btrfs]
[ 1971.525664]  ? local_clock_noinstr+0x9/0xb0
[ 1971.526009]  ? membarrier_private_expedited+0x590/0x5f0
[ 1971.526435]  btrfs_fiemap+0x75/0xb0 [btrfs]
[ 1971.526838]  ? _copy_from_user+0x46/0x80
[ 1971.527183]  do_vfs_ioctl+0x44b/0x9c0
[ 1971.527493]  __x64_sys_ioctl+0x3b/0xa0
[ 1971.527807]  do_syscall_64+0x54/0xf0
[ 1971.528110]  entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 1971.528508] RIP: 0033:0x7f736a7cc90f
[ 1971.528818] RSP: 002b:00007ffcc84ed6a0 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 1971.529404] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f736a7cc90f
[ 1971.529928] RDX: 00007ffcc84ed780 RSI: 00000000c020660b RDI: 0000000000000003
[ 1971.530449] RBP: 0000000000000000 R08: 0000000000001000 R09: 0000000000000000
[ 1971.530973] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[ 1971.531496] R13: cccccccccccccccd R14: 0000000000000000 R15: 0000000000000000
[ 1971.532022]  </TASK>
[ 1971.532238] 
[ 1971.532238] Showing all locks held in the system:
[ 1971.532752] 1 lock held by khungtaskd/67:
[ 1971.533088]  #0: ffffffffb5139040 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire.constprop.0+0x7/0x30
[ 1971.533798] 6 locks held by kworker/u32:7/31716:
[ 1971.534165]  #0: ffff9e6549189158 ((wq_completion)btrfs-endio-write){+.+.}-{0:0}, at: process_one_work+0x4b6/0x5b0
[ 1971.534925]  #1: ffffa29b8d24fe58 ((work_completion)(&work->normal_work)){+.+.}-{0:0}, at: process_one_work+0x1e1/0x5b0
[ 1971.535709]  #2: ffff9e6565cda8d8 (btrfs_ordered_extent){++++}-{0:0}, at: btrfs_finish_one_ordered+0x352/0x7c0 [btrfs]
[ 1971.536512]  #3: ffff9e6579b3c658 (sb_internal#2){.+.+}-{0:0}, at: btrfs_finish_one_ordered+0xec/0x7c0 [btrfs]
[ 1971.537278]  #4: ffff9e6565cda788 (btrfs_trans_num_writers){++++}-{0:0}, at: join_transaction+0x1d9/0x5b0 [btrfs]
[ 1971.538122]  #5: ffff9e6565cda7b8 (btrfs_trans_num_extwriters){++++}-{0:0}, at: join_transaction+0x1d9/0x5b0 [btrfs]
[ 1971.538914] 3 locks held by kworker/u32:17/12530:
[ 1971.539345]  #0: ffff9e6540ae5958 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x4b6/0x5b0
[ 1971.541544]  #1: ffffa29b8f13fe58 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x1e1/0x5b0
[ 1971.542536]  #2: ffff9e6579b3c0e8 (&type->s_umount_key#27){++++}-{3:3}, at: super_trylock_shared+0x11/0x50
[ 1971.543432] 2 locks held by 058/22683:
[ 1971.543819]  #0: ffff9e654348d0a8 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x1f/0x50
[ 1971.544595]  #1: ffffa29b803c32f8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x525/0x680
[ 1971.545458] 1 lock held by btrfs-transacti/17886:
[ 1971.545925]  #0: ffff9e6565cd8860 (&fs_info->transaction_kthread_mutex){+.+.}-{3:3}, at: transaction_kthread+0x5f/0x1b0 [btrfs]
[ 1971.546994] 2 locks held by dd/17899:
[ 1971.547369]  #0: ffff9e6579b3c438 (sb_writers#9){.+.+}-{0:0}, at: ksys_write+0x72/0xf0
[ 1971.548116]  #1: ffff9e64aae55ef0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: btrfs_inode_lock+0x33/0x60 [btrfs]
[ 1971.549109] 1 lock held by filefrag/978:
[ 1971.549507]  #0: ffff9e64aae55ef0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: btrfs_inode_lock+0x5b/0x60 [btrfs]
[ 1971.550483] 
[ 1971.550686] =============================================
[ 1971.550686] 
[ 2462.996884] INFO: task kworker/u32:7:31716 blocked for more than 983 seconds.
[ 2462.997566]       Not tainted 6.12.0-rc6-default+ #482
[ 2462.997984] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2462.998575] task:kworker/u32:7   state:D stack:10544 pid:31716 tgid:31716 ppid:2      flags:0x00004000
[ 2462.999280] Workqueue: btrfs-endio-write btrfs_work_helper [btrfs]
[ 2462.999799] Call Trace:
[ 2463.000030]  <TASK>
[ 2463.000244]  __schedule+0x2f9/0x760
[ 2463.000546]  schedule+0x35/0xe0
[ 2463.000826]  wait_extent_bit+0x248/0x290 [btrfs]
[ 2463.001277]  ? swake_up_locked+0x50/0x50
[ 2463.001607]  __lock_extent+0x94/0xe0 [btrfs]
[ 2463.001993]  btrfs_finish_one_ordered+0x3b0/0x7c0 [btrfs]
[ 2463.002445]  ? lock_acquire+0xb4/0x2f0
[ 2463.002764]  ? process_one_work+0x1e1/0x5b0
[ 2463.003124]  btrfs_work_helper+0xf6/0x270 [btrfs]
[ 2463.003535]  process_one_work+0x222/0x5b0
[ 2463.003995]  worker_thread+0x1d3/0x380
[ 2463.004329]  ? rescuer_thread+0x3e0/0x3e0
[ 2463.004663]  kthread+0xca/0x100
[ 2463.004971]  ? kthread_park+0x80/0x80
[ 2463.005303]  ret_from_fork+0x28/0x40
[ 2463.005611]  ? kthread_park+0x80/0x80
[ 2463.005921]  ret_from_fork_asm+0x11/0x20
[ 2463.006245]  </TASK>
[ 2463.006467] INFO: task kworker/u32:17:12530 blocked for more than 983 seconds.
[ 2463.007026]       Not tainted 6.12.0-rc6-default+ #482
[ 2463.007430] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2463.008029] task:kworker/u32:17  state:D stack:10664 pid:12530 tgid:12530 ppid:2      flags:0x00004000
[ 2463.008727] Workqueue: writeback wb_workfn (flush-btrfs-493)
[ 2463.009176] Call Trace:
[ 2463.009411]  <TASK>
[ 2463.009621]  __schedule+0x2f9/0x760
[ 2463.009921]  schedule+0x35/0xe0
[ 2463.010197]  wait_extent_bit+0x248/0x290 [btrfs]
[ 2463.010608]  ? swake_up_locked+0x50/0x50
[ 2463.010932]  __lock_extent+0x94/0xe0 [btrfs]
[ 2463.011320]  find_lock_delalloc_range+0x1c0/0x2b0 [btrfs]
[ 2463.011776]  writepage_delalloc+0x14b/0x3c0 [btrfs]
[ 2463.012193]  extent_writepage+0xd8/0x320 [btrfs]
[ 2463.012594]  extent_write_cache_pages+0x1bd/0x4e0 [btrfs]
[ 2463.013047]  btrfs_writepages+0x6d/0x120 [btrfs]
[ 2463.013533]  ? end_bbio_meta_write+0x460/0x460 [btrfs]
[ 2463.014055]  do_writepages+0x6f/0x280
[ 2463.014410]  ? writeback_sb_inodes+0x161/0x580
[ 2463.014859]  ? local_clock_noinstr+0x9/0xb0
[ 2463.015214]  __writeback_single_inode+0x56/0x380
[ 2463.015586]  writeback_sb_inodes+0x222/0x580
[ 2463.015940]  __writeback_inodes_wb+0x43/0xf0
[ 2463.016291]  wb_writeback+0x1ae/0x440
[ 2463.016602]  wb_do_writeback+0x2b8/0x2d0
[ 2463.016933]  wb_workfn+0x53/0x1f0
[ 2463.017229]  process_one_work+0x222/0x5b0
[ 2463.017562]  ? process_one_work+0x252/0x5b0
[ 2463.017906]  worker_thread+0x1d3/0x380
[ 2463.018227]  ? rescuer_thread+0x3e0/0x3e0
[ 2463.018562]  kthread+0xca/0x100
[ 2463.018836]  ? kthread_park+0x80/0x80
[ 2463.019146]  ret_from_fork+0x28/0x40
[ 2463.019451]  ? kthread_park+0x80/0x80
[ 2463.019759]  ret_from_fork_asm+0x11/0x20
[ 2463.020082]  </TASK>
[ 2463.020301] INFO: task btrfs-transacti:17886 blocked for more than 983 seconds.
[ 2463.020883]       Not tainted 6.12.0-rc6-default+ #482
[ 2463.021371] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2463.022098] task:btrfs-transacti state:D stack:13808 pid:17886 tgid:17886 ppid:2      flags:0x00004000
[ 2463.022934] Call Trace:
[ 2463.023209]  <TASK>
[ 2463.023457]  __schedule+0x2f9/0x760
[ 2463.023820]  schedule+0x35/0xe0
[ 2463.024155]  btrfs_commit_transaction+0x372/0xf80 [btrfs]
[ 2463.024710]  ? start_transaction+0xbb/0x8d0 [btrfs]
[ 2463.025218]  ? swake_up_locked+0x50/0x50
[ 2463.025620]  transaction_kthread+0x151/0x1b0 [btrfs]
[ 2463.026126]  ? btrfs_cleanup_transaction.isra.0+0x460/0x460 [btrfs]
[ 2463.026737]  kthread+0xca/0x100
[ 2463.027071]  ? kthread_park+0x80/0x80
[ 2463.027450]  ret_from_fork+0x28/0x40
[ 2463.027821]  ? kthread_park+0x80/0x80
[ 2463.028201]  ret_from_fork_asm+0x11/0x20
[ 2463.028602]  </TASK>
[ 2463.028865] INFO: task dd:17899 blocked for more than 983 seconds.
[ 2463.029433]       Not tainted 6.12.0-rc6-default+ #482
[ 2463.029932] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2463.030672] task:dd              state:D stack:12640 pid:17899 tgid:17899 ppid:17894  flags:0x00000000
[ 2463.031536] Call Trace:
[ 2463.031814]  <TASK>
[ 2463.032063]  __schedule+0x2f9/0x760
[ 2463.032427]  schedule+0x35/0xe0
[ 2463.032762]  schedule_preempt_disabled+0x10/0x20
[ 2463.033226]  rwsem_down_write_slowpath+0x1fa/0x620
[ 2463.033695]  ? btrfs_inode_lock+0x33/0x60 [btrfs]
[ 2463.034184]  down_write+0x1ea/0x200
[ 2463.034528]  btrfs_inode_lock+0x33/0x60 [btrfs]
[ 2463.035008]  btrfs_buffered_write+0xa2/0x7a0 [btrfs]
[ 2463.035520]  ? __might_fault+0x2f/0x70
[ 2463.035906]  btrfs_do_write_iter+0x17e/0x1e0 [btrfs]
[ 2463.036416]  ? lock_is_held_type+0x83/0xf0
[ 2463.036828]  vfs_write+0x2c2/0x5b0
[ 2463.037182]  ksys_write+0x72/0xf0
[ 2463.037531]  do_syscall_64+0x54/0xf0
[ 2463.037910]  entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 2463.038402] RIP: 0033:0x7f867d6daea4
[ 2463.038773] RSP: 002b:00007ffc07484498 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
[ 2463.039492] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f867d6daea4
[ 2463.040121] RDX: 0000000000100000 RSI: 00007f867d4c7000 RDI: 0000000000000001
[ 2463.040756] RBP: 0000000000100000 R08: 00007f867d823480 R09: 0000000000000000
[ 2463.041421] R10: 0000000000000022 R11: 0000000000000202 R12: 00007f867d4c7000
[ 2463.041997] R13: 0000000000000200 R14: 0000000000100000 R15: 0000000000000000
[ 2463.042521]  </TASK>
[ 2463.042736] INFO: task filefrag:978 blocked for more than 983 seconds.
[ 2463.043224]       Not tainted 6.12.0-rc6-default+ #482
[ 2463.043625] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2463.044209] task:filefrag        state:D stack:12608 pid:978   tgid:978   ppid:17895  flags:0x00004000
[ 2463.045100] Call Trace:
[ 2463.045388]  <TASK>
[ 2463.045650]  __schedule+0x2f9/0x760
[ 2463.046034]  ? wait_current_trans+0xae/0x1a0 [btrfs]
[ 2463.046578]  schedule+0x35/0xe0
[ 2463.046937]  wait_current_trans+0x131/0x1a0 [btrfs]
[ 2463.047475]  ? swake_up_locked+0x50/0x50
[ 2463.047901]  start_transaction+0x410/0x8d0 [btrfs]
[ 2463.048419]  btrfs_is_data_extent_shared+0xf2/0x670 [btrfs]
[ 2463.049028]  ? mark_held_locks+0x40/0x70
[ 2463.049450]  extent_fiemap+0x63e/0x950 [btrfs]
[ 2463.049953]  ? local_clock_noinstr+0x9/0xb0
[ 2463.050395]  ? membarrier_private_expedited+0x590/0x5f0
[ 2463.050929]  btrfs_fiemap+0x75/0xb0 [btrfs]
[ 2463.051413]  ? _copy_from_user+0x46/0x80
[ 2463.051835]  do_vfs_ioctl+0x44b/0x9c0
[ 2463.052256]  __x64_sys_ioctl+0x3b/0xa0
[ 2463.052658]  do_syscall_64+0x54/0xf0
[ 2463.053052]  entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 2463.053570] RIP: 0033:0x7f736a7cc90f
[ 2463.053994] RSP: 002b:00007ffcc84ed6a0 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 2463.054651] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f736a7cc90f
[ 2463.055173] RDX: 00007ffcc84ed780 RSI: 00000000c020660b RDI: 0000000000000003
[ 2463.055703] RBP: 0000000000000000 R08: 0000000000001000 R09: 0000000000000000
[ 2463.056241] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[ 2463.056791] R13: cccccccccccccccd R14: 0000000000000000 R15: 0000000000000000
[ 2463.057358]  </TASK>
[ 2463.057577] Future hung task reports are suppressed, see sysctl kernel.hung_task_warnings
[ 2463.059505] 
[ 2463.059505] Showing all locks held in the system:
[ 2463.060007] 1 lock held by khungtaskd/67:
[ 2463.060332]  #0: ffffffffb5139040 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire.constprop.0+0x7/0x30
[ 2463.061043] 6 locks held by kworker/u32:7/31716:
[ 2463.061417]  #0: ffff9e6549189158 ((wq_completion)btrfs-endio-write){+.+.}-{0:0}, at: process_one_work+0x4b6/0x5b0
[ 2463.062182]  #1: ffffa29b8d24fe58 ((work_completion)(&work->normal_work)){+.+.}-{0:0}, at: process_one_work+0x1e1/0x5b0
[ 2463.062984]  #2: ffff9e6565cda8d8 (btrfs_ordered_extent){++++}-{0:0}, at: btrfs_finish_one_ordered+0x352/0x7c0 [btrfs]
[ 2463.063813]  #3: ffff9e6579b3c658 (sb_internal#2){.+.+}-{0:0}, at: btrfs_finish_one_ordered+0xec/0x7c0 [btrfs]
[ 2463.064674]  #4: ffff9e6565cda788 (btrfs_trans_num_writers){++++}-{0:0}, at: join_transaction+0x1d9/0x5b0 [btrfs]
[ 2463.065467]  #5: ffff9e6565cda7b8 (btrfs_trans_num_extwriters){++++}-{0:0}, at: join_transaction+0x1d9/0x5b0 [btrfs]
[ 2463.066263] 3 locks held by kworker/u32:17/12530:
[ 2463.066642]  #0: ffff9e6540ae5958 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x4b6/0x5b0
[ 2463.067358]  #1: ffffa29b8f13fe58 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x1e1/0x5b0
[ 2463.068149]  #2: ffff9e6579b3c0e8 (&type->s_umount_key#27){++++}-{3:3}, at: super_trylock_shared+0x11/0x50
[ 2463.068879] 2 locks held by 058/22683:
[ 2463.069282]  #0: ffff9e654348d0a8 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x1f/0x50
[ 2463.070238]  #1: ffffa29b803c32f8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x525/0x680
[ 2463.071203] 1 lock held by btrfs-transacti/17886:
[ 2463.071600]  #0: ffff9e6565cd8860 (&fs_info->transaction_kthread_mutex){+.+.}-{3:3}, at: transaction_kthread+0x5f/0x1b0 [btrfs]
[ 2463.072490] 2 locks held by dd/17899:
[ 2463.072877]  #0: ffff9e6579b3c438 (sb_writers#9){.+.+}-{0:0}, at: ksys_write+0x72/0xf0
[ 2463.073499]  #1: ffff9e64aae55ef0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: btrfs_inode_lock+0x33/0x60 [btrfs]
[ 2463.074477] 1 lock held by filefrag/978:
[ 2463.074838]  #0: ffff9e64aae55ef0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: btrfs_inode_lock+0x5b/0x60 [btrfs]
[ 2463.075627] 
[ 2463.075801] =============================================
[ 2463.075801]