2022-07-23 09:44:06

by kernel test robot

[permalink] [raw]
Subject: [xfs] d6c6912258: INFO:task_blocked_for_more_than#seconds



Greeting,

FYI, we noticed the following commit (built with gcc-11):

commit: d6c6912258aa02f82a8c6a21983c6f6c2743af7e ("[PATCH v2 15/18] xfs: Add parent pointers to rename")
url: https://github.com/intel-lab-lkp/linux/commits/Allison-Henderson/Parent-Pointers/20220719-044053
base: https://git.kernel.org/cgit/fs/xfs/xfs-linux.git for-next
patch link: https://lore.kernel.org/linux-xfs/[email protected]

in testcase: xfstests
version: xfstests-x86_64-c1144bf-1_20220711
with following parameters:

disk: 4HDD
fs: xfs
test: xfs-group-53
ucode: 0x21

test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git


on test machine: 4 threads 1 sockets Intel(R) Core(TM) i3-3220 CPU @ 3.30GHz with 8G memory

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):



If you fix the issue, kindly add following tag
Reported-by: kernel test robot <[email protected]>


[ 988.549008][ T43] INFO: task fsstress:4716 blocked for more than 491 seconds.
[ 988.549169][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.549310][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.549457][ T43] task:fsstress state:D stack: 0 pid: 4716 ppid: 4715 flags:0x00000000
[ 988.549618][ T43] Call Trace:
[ 988.549682][ T43] <TASK>
[ 988.549741][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.549828][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.549957][ T43] ? __legitimize_path (fs/namei.c:699)
[ 988.550061][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.550147][ T43] schedule_preempt_disabled (arch/x86/include/asm/preempt.h:80 kernel/sched/core.c:6590)
[ 988.550259][ T43] __mutex_lock+0x85c/0x1040
[ 988.550378][ T43] ? path_parentat (fs/namei.c:2556)
[ 988.550482][ T43] ? ww_mutex_lock (kernel/locking/mutex.c:744)
[ 988.550589][ T43] mutex_lock (kernel/locking/mutex.c:286)
[ 988.550685][ T43] ? __mutex_lock_slowpath (kernel/locking/mutex.c:282)
[ 988.550797][ T43] ? _copy_to_user (arch/x86/include/asm/uaccess_64.h:46 arch/x86/include/asm/uaccess_64.h:58 lib/usercopy.c:33)
[ 988.550898][ T43] ? cp_new_stat (fs/stat.c:356)
[ 988.551022][ T43] lock_rename (fs/namei.c:3015)
[ 988.551120][ T43] do_renameat2 (fs/namei.c:4813)
[ 988.551224][ T43] ? __x64_sys_link (fs/namei.c:4760)
[ 988.551330][ T43] ? check_heap_object (arch/x86/include/asm/bitops.h:207 include/asm-generic/bitops/instrumented-non-atomic.h:135 include/linux/page-flags.h:506 mm/usercopy.c:193)
[ 988.551443][ T43] ? __check_object_size (mm/memremap.c:37)
[ 988.551569][ T43] ? strncpy_from_user (arch/x86/include/asm/uaccess.h:542 lib/strncpy_from_user.c:138)
[ 988.551681][ T43] ? getname_flags (fs/namei.c:205)
[ 988.551795][ T43] __x64_sys_rename (fs/namei.c:4914)
[ 988.551884][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.551993][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.552118][ T43] RIP: 0033:0x7fcab427bed7
[ 988.552218][ T43] RSP: 002b:00007ffc5e54ca18 EFLAGS: 00000246 ORIG_RAX: 0000000000000052
[ 988.552369][ T43] RAX: ffffffffffffffda RBX: 00007ffc5e54cce0 RCX: 00007fcab427bed7
[ 988.552506][ T43] RDX: 0000000000000000 RSI: 0000555ea31b9210 RDI: 0000555ea31b91d0
[ 988.552643][ T43] RBP: 00007ffc5e54ccf0 R08: 0000000000000001 R09: 0000000000000002
[ 988.552780][ T43] R10: 00007ffc5e54ccf0 R11: 0000000000000246 R12: 0000000000000000
[ 988.552948][ T43] R13: 00007ffc5e54cce0 R14: 000000000000000b R15: 00007ffc5e54ccc4
[ 988.553108][ T43] </TASK>
[ 988.553184][ T43] INFO: task fsstress:4717 blocked for more than 491 seconds.
[ 988.553334][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.553492][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.553640][ T43] task:fsstress state:D stack: 0 pid: 4717 ppid: 4715 flags:0x00004000
[ 988.553799][ T43] Call Trace:
[ 988.553865][ T43] <TASK>
[ 988.553945][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.554048][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.554161][ T43] ? select_idle_cpu (kernel/sched/fair.c:6397)
[ 988.554272][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.554366][ T43] schedule_timeout (kernel/time/timer.c:1912)
[ 988.554476][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.554572][ T43] ? set_next_entity (kernel/sched/fair.c:4536)
[ 988.554663][ T43] ? update_curr (kernel/sched/fair.c:917)
[ 988.554751][ T43] ? _raw_spin_lock_irq (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:120 kernel/locking/spinlock.c:170)
[ 988.554845][ T43] ? _raw_spin_lock_bh (kernel/locking/spinlock.c:169)
[ 988.554958][ T43] ? finish_task_switch+0x1c2/0x740
[ 988.555085][ T43] __wait_for_common (kernel/sched/completion.c:86 kernel/sched/completion.c:106)
[ 988.555193][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.555306][ T43] ? out_of_line_wait_on_bit_timeout (kernel/sched/completion.c:100)
[ 988.555437][ T43] ? mutex_unlock (arch/x86/include/asm/atomic64_64.h:190 include/linux/atomic/atomic-long.h:449 include/linux/atomic/atomic-instrumented.h:1790 kernel/locking/mutex.c:181 kernel/locking/mutex.c:540)
[ 988.555540][ T43] ? __mutex_unlock_slowpath+0x2c0/0x2c0
[ 988.555661][ T43] __flush_workqueue (kernel/workqueue.c:2869)
[ 988.555753][ T43] ? migrate_swap_stop (kernel/sched/core.c:4047)
[ 988.555848][ T43] ? flush_workqueue_prep_pwqs (kernel/workqueue.c:2798)
[ 988.555988][ T43] ? cpumask_next (lib/cpumask.c:23)
[ 988.556090][ T43] ? xfs_inodegc_queue_all (fs/xfs/xfs_icache.c:442 (discriminator 1)) xfs
[ 988.556429][ T43] xfs_trans_alloc (include/linux/slab.h:723 fs/xfs/xfs_trans.c:261) xfs
[ 988.556649][ T43] xfs_trans_alloc_icreate (fs/xfs/xfs_trans.c:1247) xfs
[ 988.556876][ T43] ? xfs_trans_alloc_inode (fs/xfs/xfs_trans.c:1240) xfs
[ 988.557137][ T43] xfs_create (fs/xfs/xfs_inode.c:1017) xfs
[ 988.557428][ T43] ? xfs_dir_lookup (fs/xfs/libxfs/xfs_dir2.c:362) xfs
[ 988.557711][ T43] ? xfs_irele (fs/xfs/xfs_inode.c:961) xfs
[ 988.557947][ T43] ? xfs_dir_canenter (fs/xfs/libxfs/xfs_dir2.c:362) xfs
[ 988.558225][ T43] ? memcg_reparent_list_lrus (mm/list_lru.c:474)
[ 988.558332][ T43] ? _raw_spin_lock (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:134 kernel/locking/spinlock.c:154)
[ 988.558422][ T43] ? _raw_write_lock_irq (kernel/locking/spinlock.c:153)
[ 988.558518][ T43] ? posix_acl_fix_xattr_userns (fs/posix_acl.c:41)
[ 988.558623][ T43] ? __d_add (fs/dcache.c:2740)
[ 988.558704][ T43] ? xfs_ip2xflags (fs/xfs/xfs_inode.c:658) xfs
[ 988.558949][ T43] xfs_generic_create (fs/xfs/xfs_iops.c:199) xfs
[ 988.559293][ T43] ? xfs_setup_iops (fs/xfs/xfs_iops.c:171) xfs
[ 988.559621][ T43] ? d_alloc (include/linux/list.h:69 include/linux/list.h:88 fs/dcache.c:1859)
[ 988.559702][ T43] ? filename_create (fs/namei.c:3747)
[ 988.559793][ T43] ? inode_permission (fs/namei.c:526 fs/namei.c:500)
[ 988.559885][ T43] vfs_mkdir (fs/namei.c:3975)
[ 988.559993][ T43] do_mkdirat (fs/namei.c:3795 fs/namei.c:4004)
[ 988.560093][ T43] ? do_file_open_root (fs/namei.c:3983)
[ 988.560193][ T43] ? getname_flags (fs/namei.c:205)
[ 988.560291][ T43] __x64_sys_mkdir (fs/namei.c:4019)
[ 988.560380][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.560464][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.560571][ T43] RIP: 0033:0x7fcab4312b07
[ 988.560655][ T43] RSP: 002b:00007ffc5e54cb98 EFLAGS: 00000246 ORIG_RAX: 0000000000000053
[ 988.560801][ T43] RAX: ffffffffffffffda RBX: 0000000000000014 RCX: 00007fcab4312b07
[ 988.560972][ T43] RDX: 0000000000000000 RSI: 00000000000001ff RDI: 0000555ea31c7360
[ 988.561130][ T43] RBP: 00007ffc5e54cd10 R08: 0000000000000001 R09: 0000000000000003
[ 988.561276][ T43] R10: 00007ffc5e54c806 R11: 0000000000000246 R12: 00000000000000ab
[ 988.561413][ T43] R13: 00000000000001ff R14: 0000555ea2079720 R15: 00000000000000ab
[ 988.561551][ T43] </TASK>
[ 988.561612][ T43] INFO: task fsstress:4718 blocked for more than 491 seconds.
[ 988.561741][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.561881][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.562051][ T43] task:fsstress state:D stack: 0 pid: 4718 ppid: 4715 flags:0x00000000
[ 988.562225][ T43] Call Trace:
[ 988.562290][ T43] <TASK>
[ 988.562350][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.562435][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.562531][ T43] ? __legitimize_path (fs/namei.c:699)
[ 988.562625][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.562704][ T43] schedule_preempt_disabled (arch/x86/include/asm/preempt.h:80 kernel/sched/core.c:6590)
[ 988.562803][ T43] __mutex_lock+0x85c/0x1040
[ 988.562906][ T43] ? path_parentat (fs/namei.c:2556)
[ 988.563030][ T43] ? ww_mutex_lock (kernel/locking/mutex.c:744)
[ 988.563125][ T43] mutex_lock (kernel/locking/mutex.c:286)
[ 988.563208][ T43] ? __mutex_lock_slowpath (kernel/locking/mutex.c:282)
[ 988.563306][ T43] ? filp_close (fs/open.c:1396)
[ 988.563389][ T43] ? __x64_sys_close (fs/open.c:1410 fs/open.c:1405 fs/open.c:1405)
[ 988.563477][ T43] ? entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.563585][ T43] lock_rename (fs/namei.c:3015)
[ 988.563667][ T43] do_renameat2 (fs/namei.c:4813)
[ 988.563752][ T43] ? __x64_sys_link (fs/namei.c:4760)
[ 988.563838][ T43] ? call_rcu (arch/x86/include/asm/atomic64_64.h:22 include/linux/atomic/atomic-long.h:29 include/linux/atomic/atomic-instrumented.h:1266 kernel/rcu/rcu_segcblist.h:50 kernel/rcu/tree.c:2991 kernel/rcu/tree.c:3160)
[ 988.563955][ T43] ? check_heap_object (arch/x86/include/asm/bitops.h:207 include/asm-generic/bitops/instrumented-non-atomic.h:135 include/linux/page-flags.h:506 mm/usercopy.c:193)
[ 988.564066][ T43] ? __check_object_size (mm/memremap.c:37)
[ 988.564182][ T43] ? strncpy_from_user (arch/x86/include/asm/uaccess.h:542 lib/strncpy_from_user.c:138)
[ 988.564276][ T43] ? getname_flags (fs/namei.c:205)
[ 988.564374][ T43] __x64_sys_rename (fs/namei.c:4914)
[ 988.564463][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.564547][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.564654][ T43] RIP: 0033:0x7fcab427bed7
[ 988.564737][ T43] RSP: 002b:00007ffc5e54ca18 EFLAGS: 00000246 ORIG_RAX: 0000000000000052
[ 988.564886][ T43] RAX: ffffffffffffffda RBX: 00007ffc5e54cce0 RCX: 00007fcab427bed7
[ 988.565046][ T43] RDX: 0000000000000000 RSI: 0000555ea31c4320 RDI: 0000555ea31c42c0
[ 988.565200][ T43] RBP: 00007ffc5e54ccf0 R08: 0000000000000001 R09: 0000000000000003
[ 988.565338][ T43] R10: 00007ffc5e54ccf0 R11: 0000000000000246 R12: 0000000000000000
[ 988.565475][ T43] R13: 00007ffc5e54cce0 R14: 0000000000000035 R15: 00007ffc5e54ccc4
[ 988.565613][ T43] </TASK>
[ 988.565673][ T43] INFO: task fsstress:4719 blocked for more than 491 seconds.
[ 988.565802][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.565972][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.566130][ T43] task:fsstress state:D stack: 0 pid: 4719 ppid: 4715 flags:0x00000000
[ 988.566288][ T43] Call Trace:
[ 988.566351][ T43] <TASK>
[ 988.566410][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.566495][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.566591][ T43] ? __legitimize_path (fs/namei.c:699)
[ 988.566685][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.566766][ T43] schedule_preempt_disabled (arch/x86/include/asm/preempt.h:80 kernel/sched/core.c:6590)
[ 988.566865][ T43] __mutex_lock+0x85c/0x1040
[ 988.566988][ T43] ? path_parentat (fs/namei.c:2556)
[ 988.567095][ T43] ? ww_mutex_lock (kernel/locking/mutex.c:744)
[ 988.567193][ T43] ? finish_task_switch+0x1c2/0x740
[ 988.567298][ T43] ? __switch_to (arch/x86/include/asm/bitops.h:55 include/asm-generic/bitops/instrumented-atomic.h:29 include/linux/thread_info.h:89 arch/x86/include/asm/fpu/sched.h:65 arch/x86/kernel/process_64.c:622)
[ 988.567386][ T43] mutex_lock (kernel/locking/mutex.c:286)
[ 988.567467][ T43] ? __mutex_lock_slowpath (kernel/locking/mutex.c:282)
[ 988.567563][ T43] ? _raw_spin_lock_irqsave (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:111 kernel/locking/spinlock.c:162)
[ 988.567661][ T43] lock_rename (fs/namei.c:3015)
[ 988.567742][ T43] do_renameat2 (fs/namei.c:4813)
[ 988.567828][ T43] ? __x64_sys_link (fs/namei.c:4760)
[ 988.567940][ T43] ? qlist_free_all (mm/kasan/quarantine.c:182)
[ 988.568049][ T43] ? check_heap_object (arch/x86/include/asm/bitops.h:207 include/asm-generic/bitops/instrumented-non-atomic.h:135 include/linux/page-flags.h:506 mm/usercopy.c:193)
[ 988.568151][ T43] ? __check_object_size (mm/memremap.c:37)
[ 988.568256][ T43] ? strncpy_from_user (arch/x86/include/asm/uaccess.h:542 lib/strncpy_from_user.c:138)
[ 988.568351][ T43] ? getname_flags (fs/namei.c:205)
[ 988.568450][ T43] __x64_sys_rename (fs/namei.c:4914)
[ 988.568539][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.568623][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.568728][ T43] RIP: 0033:0x7fcab427bed7
[ 988.568811][ T43] RSP: 002b:00007ffc5e54ca18 EFLAGS: 00000246 ORIG_RAX: 0000000000000052
[ 988.568980][ T43] RAX: ffffffffffffffda RBX: 00007ffc5e54cce0 RCX: 00007fcab427bed7
[ 988.569140][ T43] RDX: 0000000000000000 RSI: 0000555ea31b83f0 RDI: 0000555ea31b95f0
[ 988.569282][ T43] RBP: 00007ffc5e54ccf0 R08: 0000000000000001 R09: 0000000000000002
[ 988.569420][ T43] R10: 00007ffc5e54ccf0 R11: 0000000000000246 R12: 0000000000000000
[ 988.569557][ T43] R13: 00007ffc5e54cce0 R14: 0000000000000003 R15: 00007ffc5e54ccc4
[ 988.569694][ T43] </TASK>
[ 988.569754][ T43] INFO: task fsstress:4721 blocked for more than 491 seconds.
[ 988.569884][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.570044][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.570205][ T43] task:fsstress state:D stack: 0 pid: 4721 ppid: 4715 flags:0x00004000
[ 988.570365][ T43] Call Trace:
[ 988.570429][ T43] <TASK>
[ 988.570489][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.570573][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.570670][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.570747][ T43] schedule_timeout (kernel/time/timer.c:1912)
[ 988.570837][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.570960][ T43] ? check_preempt_curr (arch/x86/include/asm/bitops.h:207 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:135 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2011 (discriminator 1) include/linux/sched.h:2026 (discriminator 1) kernel/sched/core.c:2197 (discriminator 1))
[ 988.571074][ T43] ? _raw_spin_lock_irq (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:120 kernel/locking/spinlock.c:170)
[ 988.571172][ T43] ? _raw_spin_lock_bh (kernel/locking/spinlock.c:169)
[ 988.571267][ T43] __wait_for_common (kernel/sched/completion.c:86 kernel/sched/completion.c:106)
[ 988.571360][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.571457][ T43] ? out_of_line_wait_on_bit_timeout (kernel/sched/completion.c:100)
[ 988.571569][ T43] ? mutex_unlock (arch/x86/include/asm/atomic64_64.h:190 include/linux/atomic/atomic-long.h:449 include/linux/atomic/atomic-instrumented.h:1790 kernel/locking/mutex.c:181 kernel/locking/mutex.c:540)
[ 988.571654][ T43] ? __mutex_unlock_slowpath+0x2c0/0x2c0
[ 988.571772][ T43] __flush_workqueue (kernel/workqueue.c:2869)
[ 988.571866][ T43] ? try_to_grab_pending+0x33f/0x480
[ 988.571994][ T43] ? queue_delayed_work_on (kernel/workqueue.c:1730)
[ 988.572110][ T43] ? flush_workqueue_prep_pwqs (kernel/workqueue.c:2798)
[ 988.572219][ T43] ? cpumask_next (lib/cpumask.c:23)
[ 988.572305][ T43] ? xfs_inodegc_queue_all (fs/xfs/xfs_icache.c:442 (discriminator 1)) xfs
[ 988.572544][ T43] xfs_trans_alloc (include/linux/slab.h:723 fs/xfs/xfs_trans.c:261) xfs
[ 988.572765][ T43] xfs_trans_alloc_inode (fs/xfs/xfs_trans.c:1194) xfs
[ 988.573013][ T43] ? xfs_trans_roll (fs/xfs/xfs_trans.c:1184) xfs
[ 988.573279][ T43] ? xfs_trans_free_items (fs/xfs/xfs_trans.c:947) xfs
[ 988.573505][ T43] ? xfs_bunmapi (fs/xfs/libxfs/xfs_bmap.c:5580) xfs
[ 988.573701][ T43] xfs_alloc_file_space (fs/xfs/xfs_bmap_util.c:859) xfs
[ 988.573948][ T43] ? xfs_free_eofblocks (fs/xfs/xfs_bmap_util.c:777) xfs
[ 988.574228][ T43] ? xfs_prepare_shift (fs/xfs/xfs_bmap_util.c:969) xfs
[ 988.574445][ T43] xfs_file_fallocate (fs/xfs/xfs_file.c:1033) xfs
[ 988.574660][ T43] ? xfs_break_layouts (fs/xfs/xfs_file.c:886) xfs
[ 988.574876][ T43] ? __do_sys_newfstat (fs/stat.c:438)
[ 988.574993][ T43] ? __ia32_sys_fstat (fs/stat.c:433)
[ 988.575097][ T43] vfs_fallocate (fs/open.c:323)
[ 988.575183][ T43] __x64_sys_fallocate (include/linux/file.h:44 fs/open.c:347 fs/open.c:354 fs/open.c:352 fs/open.c:352)
[ 988.575276][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.575359][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.575467][ T43] RIP: 0033:0x7fcab4317cd6
[ 988.575550][ T43] RSP: 002b:00007ffc5e54c838 EFLAGS: 00000246 ORIG_RAX: 000000000000011d
[ 988.575696][ T43] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 00007fcab4317cd6
[ 988.575832][ T43] RDX: 00000000000537dd RSI: 0000000000000010 RDI: 0000000000000004
[ 988.575990][ T43] RBP: 0000000000000004 R08: 0000000000000054 R09: 0000000000000003
[ 988.576140][ T43] R10: 00000000000c979d R11: 0000000000000246 R12: 000000000000015f
[ 988.576278][ T43] R13: 00000000000c979d R14: 00000000000537dd R15: 0000000000000010
[ 988.576417][ T43] </TASK>
[ 988.576477][ T43] INFO: task fsstress:4723 blocked for more than 491 seconds.
[ 988.576606][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.576748][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.576896][ T43] task:fsstress state:D stack: 0 pid: 4723 ppid: 4715 flags:0x00004000
[ 988.577078][ T43] Call Trace:
[ 988.577154][ T43] <TASK>
[ 988.577225][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.577315][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.577412][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.577510][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.577589][ T43] schedule_timeout (kernel/time/timer.c:1912)
[ 988.577679][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.577774][ T43] ? schedule_timeout (kernel/time/timer.c:1912)
[ 988.577867][ T43] ? _raw_spin_lock_irq (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:120 kernel/locking/spinlock.c:170)
[ 988.577979][ T43] ? _raw_spin_lock_bh (kernel/locking/spinlock.c:169)
[ 988.578083][ T43] ? _raw_spin_lock_irq (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:120 kernel/locking/spinlock.c:170)
[ 988.578189][ T43] __wait_for_common (kernel/sched/completion.c:86 kernel/sched/completion.c:106)
[ 988.578290][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.578385][ T43] ? out_of_line_wait_on_bit_timeout (kernel/sched/completion.c:100)
[ 988.578500][ T43] ? mutex_unlock (arch/x86/include/asm/atomic64_64.h:190 include/linux/atomic/atomic-long.h:449 include/linux/atomic/atomic-instrumented.h:1790 kernel/locking/mutex.c:181 kernel/locking/mutex.c:540)
[ 988.578586][ T43] ? __mutex_unlock_slowpath+0x2c0/0x2c0
[ 988.578704][ T43] __flush_workqueue (kernel/workqueue.c:2869)
[ 988.578797][ T43] ? flush_workqueue_prep_pwqs (kernel/workqueue.c:2798)
[ 988.578901][ T43] ? xfs_inodegc_queue_all (fs/xfs/xfs_icache.c:442 (discriminator 1)) xfs
[ 988.579188][ T43] xfs_trans_alloc (include/linux/slab.h:723 fs/xfs/xfs_trans.c:261) xfs
[ 988.579418][ T43] xfs_trans_alloc_dir (fs/xfs/xfs_trans.c:1380) xfs
[ 988.579641][ T43] ? xfs_trans_alloc_ichange (fs/xfs/xfs_trans.c:1370) xfs
[ 988.579872][ T43] ? __d_rehash (arch/x86/include/asm/bitops.h:94 arch/x86/include/asm/bitops.h:113 include/asm-generic/bitops/instrumented-lock.h:43 include/linux/bit_spinlock.h:80 include/linux/list_bl.h:153 fs/dcache.c:2546)
[ 988.579977][ T43] xfs_link (fs/xfs/xfs_inode.c:1266) xfs
[ 988.580227][ T43] ? d_splice_alias (fs/dcache.c:3128)
[ 988.580317][ T43] ? xfs_init_new_inode (fs/xfs/xfs_inode.c:1234) xfs
[ 988.580540][ T43] ? posix_acl_fix_xattr_userns (fs/posix_acl.c:41)
[ 988.580647][ T43] ? userns_owner (kernel/user_namespace.c:371)
[ 988.580730][ T43] ? may_linkat (fs/namei.c:2516)
[ 988.580814][ T43] ? __cond_resched (kernel/sched/core.c:8247)
[ 988.580904][ T43] xfs_vn_link (fs/xfs/xfs_iops.c:368) xfs
[ 988.581179][ T43] ? xfs_vn_unlink (fs/xfs/xfs_iops.c:359) xfs
[ 988.581407][ T43] ? inode_permission (fs/namei.c:526 fs/namei.c:500)
[ 988.581499][ T43] vfs_link (fs/namei.c:4472)
[ 988.581580][ T43] do_linkat (fs/namei.c:4540)
[ 988.581661][ T43] ? __x64_sys_symlink (fs/namei.c:4495)
[ 988.581751][ T43] ? getname_flags (fs/namei.c:205)
[ 988.581848][ T43] __x64_sys_link (fs/namei.c:4572)
[ 988.581954][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.582055][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.582164][ T43] RIP: 0033:0x7fcab4314a37
[ 988.582247][ T43] RSP: 002b:00007ffc5e54ca28 EFLAGS: 00000246 ORIG_RAX: 0000000000000056
[ 988.582394][ T43] RAX: ffffffffffffffda RBX: 00007ffc5e54cce0 RCX: 00007fcab4314a37
[ 988.582531][ T43] RDX: 0000000000000000 RSI: 0000555ea31bbbe0 RDI: 0000555ea31bbc00
[ 988.582667][ T43] RBP: 00007ffc5e54ccf0 R08: 0000000000000001 R09: 0000000000000003
[ 988.582803][ T43] R10: 00007ffc5e54c7c6 R11: 0000000000000246 R12: 0000000000000184
[ 988.582975][ T43] R13: 000000000000002b R14: 00007ffc5e54ccc4 R15: 00007ffc5e54ccf0
[ 988.583128][ T43] </TASK>
[ 988.583190][ T43] INFO: task fsstress:4724 blocked for more than 491 seconds.
[ 988.583321][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.583461][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.583608][ T43] task:fsstress state:D stack: 0 pid: 4724 ppid: 4715 flags:0x00004000
[ 988.583766][ T43] Call Trace:
[ 988.583829][ T43] <TASK>
[ 988.583888][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.583992][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.584109][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.584215][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.584293][ T43] schedule_timeout (kernel/time/timer.c:1912)
[ 988.584383][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.584479][ T43] ? _raw_spin_lock_irq (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:120 kernel/locking/spinlock.c:170)
[ 988.584571][ T43] ? _raw_spin_lock_bh (kernel/locking/spinlock.c:169)
[ 988.584667][ T43] __wait_for_common (kernel/sched/completion.c:86 kernel/sched/completion.c:106)
[ 988.584758][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.584853][ T43] ? out_of_line_wait_on_bit_timeout (kernel/sched/completion.c:100)
[ 988.584988][ T43] ? mutex_unlock (arch/x86/include/asm/atomic64_64.h:190 include/linux/atomic/atomic-long.h:449 include/linux/atomic/atomic-instrumented.h:1790 kernel/locking/mutex.c:181 kernel/locking/mutex.c:540)
[ 988.585084][ T43] ? __mutex_unlock_slowpath+0x2c0/0x2c0
[ 988.585213][ T43] __flush_workqueue (kernel/workqueue.c:2869)
[ 988.585305][ T43] ? flush_workqueue_prep_pwqs (kernel/workqueue.c:2798)
[ 988.585410][ T43] ? cpumask_next (lib/cpumask.c:23)
[ 988.585495][ T43] ? xfs_inodegc_queue_all (fs/xfs/xfs_icache.c:442 (discriminator 1)) xfs
[ 988.585726][ T43] xfs_trans_alloc (include/linux/slab.h:723 fs/xfs/xfs_trans.c:261) xfs
[ 988.585979][ T43] xfs_trans_alloc_icreate (fs/xfs/xfs_trans.c:1247) xfs
[ 988.586267][ T43] ? xfs_trans_alloc_inode (fs/xfs/xfs_trans.c:1240) xfs
[ 988.586495][ T43] ? __kernel_text_address (kernel/extable.c:79)
[ 988.586590][ T43] ? unwind_get_return_address (arch/x86/kernel/unwind_orc.c:318 arch/x86/kernel/unwind_orc.c:313)
[ 988.586692][ T43] ? memcg_reparent_list_lrus (mm/list_lru.c:474)
[ 988.586795][ T43] xfs_create (fs/xfs/xfs_inode.c:1017) xfs
[ 988.587046][ T43] ? _raw_read_unlock_irqrestore (kernel/locking/spinlock.c:161)
[ 988.587159][ T43] ? xfs_irele (fs/xfs/xfs_inode.c:961) xfs
[ 988.587369][ T43] ? kasan_set_track (mm/kasan/common.c:45)
[ 988.587461][ T43] ? posix_acl_fix_xattr_userns (fs/posix_acl.c:41)
[ 988.587566][ T43] ? xfs_dir_canenter (fs/xfs/libxfs/xfs_dir2.c:362) xfs
[ 988.587772][ T43] xfs_generic_create (fs/xfs/xfs_iops.c:199) xfs
[ 988.588012][ T43] ? xfs_setup_iops (fs/xfs/xfs_iops.c:171) xfs
[ 988.588294][ T43] ? d_splice_alias (fs/dcache.c:3128)
[ 988.588383][ T43] ? xfs_vn_link (fs/xfs/xfs_iops.c:294) xfs
[ 988.588593][ T43] ? xfs_vn_lookup (fs/xfs/xfs_iops.c:311) xfs
[ 988.588806][ T43] ? from_kgid (kernel/user_namespace.c:491)
[ 988.588885][ T43] ? inode_permission (fs/namei.c:526 fs/namei.c:500)
[ 988.589000][ T43] ? xfs_vn_link (fs/xfs/xfs_iops.c:294) xfs
[ 988.589280][ T43] lookup_open+0xd7b/0x1740
[ 988.589375][ T43] ? do_open (fs/namei.c:3278)
[ 988.589456][ T43] ? down_write_killable (kernel/locking/rwsem.c:1540)
[ 988.589554][ T43] open_last_lookups (fs/namei.c:3444)
[ 988.589648][ T43] ? lookup_open+0x1740/0x1740
[ 988.589747][ T43] ? lookup_one_len_unlocked (fs/namei.c:2259)
[ 988.589850][ T43] path_openat (fs/namei.c:3650 (discriminator 1))
[ 988.589966][ T43] ? open_last_lookups (fs/namei.c:3635)
[ 988.590080][ T43] ? kasan_save_stack (mm/kasan/common.c:40)
[ 988.590179][ T43] ? kasan_save_stack (mm/kasan/common.c:39)
[ 988.590269][ T43] ? __kasan_record_aux_stack (mm/kasan/generic.c:348)
[ 988.590370][ T43] ? call_rcu (arch/x86/include/asm/irqflags.h:29 (discriminator 3) arch/x86/include/asm/irqflags.h:70 (discriminator 3) arch/x86/include/asm/irqflags.h:106 (discriminator 3) kernel/rcu/tree.c:3127 (discriminator 3))
[ 988.590449][ T43] ? task_work_run (kernel/task_work.c:179 (discriminator 1))
[ 988.590536][ T43] ? exit_to_user_mode_loop (include/linux/resume_user_mode.h:49 kernel/entry/common.c:169)
[ 988.590636][ T43] ? exit_to_user_mode_prepare (kernel/entry/common.c:201)
[ 988.590738][ T43] ? syscall_exit_to_user_mode (arch/x86/include/asm/jump_label.h:27 include/linux/context_tracking_state.h:31 include/linux/context_tracking.h:40 kernel/entry/common.c:132 kernel/entry/common.c:296)
[ 988.590840][ T43] do_filp_open (fs/namei.c:3680)
[ 988.590953][ T43] ? may_open_dev (fs/namei.c:3674)
[ 988.591055][ T43] ? _raw_spin_lock (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:134 kernel/locking/spinlock.c:154)
[ 988.591158][ T43] ? _raw_write_lock_irq (kernel/locking/spinlock.c:153)
[ 988.591258][ T43] ? __check_object_size (mm/memremap.c:37)
[ 988.591363][ T43] ? alloc_fd (arch/x86/include/asm/bitops.h:214 include/asm-generic/bitops/instrumented-non-atomic.h:135 fs/file.c:256 fs/file.c:542)
[ 988.591446][ T43] do_sys_openat2 (fs/open.c:1278)
[ 988.591534][ T43] ? call_rcu (arch/x86/include/asm/atomic64_64.h:22 include/linux/atomic/atomic-long.h:29 include/linux/atomic/atomic-instrumented.h:1266 kernel/rcu/rcu_segcblist.h:50 kernel/rcu/tree.c:2991 kernel/rcu/tree.c:3160)
[ 988.591614][ T43] ? build_open_flags (fs/open.c:1264)
[ 988.591708][ T43] ? __fput (include/linux/percpu_counter.h:189 fs/file_table.c:58 fs/file_table.c:335)
[ 988.591787][ T43] __x64_sys_creat (fs/open.c:1364)
[ 988.591874][ T43] ? __ia32_sys_openat2 (fs/open.c:1364)
[ 988.591990][ T43] ? exit_to_user_mode_loop (include/linux/sched.h:2292 include/linux/resume_user_mode.h:61 kernel/entry/common.c:169)
[ 988.592105][ T43] ? exit_to_user_mode_prepare (kernel/entry/common.c:201)
[ 988.592220][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.592304][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.592412][ T43] RIP: 0033:0x7fcab4313843
[ 988.592496][ T43] RSP: 002b:00007ffc5e54cb88 EFLAGS: 00000246 ORIG_RAX: 0000000000000055
[ 988.592641][ T43] RAX: ffffffffffffffda RBX: 0000000000000017 RCX: 00007fcab4313843
[ 988.592779][ T43] RDX: 0000000000000000 RSI: 00000000000001b6 RDI: 0000555ea31b83f0
[ 988.592941][ T43] RBP: 00007ffc5e54cd00 R08: 0000000000000001 R09: 0000000000000003
[ 988.593097][ T43] R10: 00007ffc5e54c7f6 R11: 0000000000000246 R12: 0000000000000085
[ 988.593242][ T43] R13: 00000000000001b6 R14: 0000555ea2078aa0 R15: 0000000000000085
[ 988.593381][ T43] </TASK>
[ 988.593441][ T43] INFO: task fsstress:4725 blocked for more than 491 seconds.
[ 988.593572][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.593710][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.593857][ T43] task:fsstress state:D stack: 0 pid: 4725 ppid: 4715 flags:0x00000000
[ 988.594037][ T43] Call Trace:
[ 988.594114][ T43] <TASK>
[ 988.594179][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.594272][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.594369][ T43] ? __legitimize_path (fs/namei.c:699)
[ 988.594461][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.594539][ T43] schedule_preempt_disabled (arch/x86/include/asm/preempt.h:80 kernel/sched/core.c:6590)
[ 988.594640][ T43] __mutex_lock+0x85c/0x1040
[ 988.594743][ T43] ? path_parentat (fs/namei.c:2556)
[ 988.594832][ T43] ? ww_mutex_lock (kernel/locking/mutex.c:744)
[ 988.594942][ T43] ? stack_trace_save (kernel/stacktrace.c:123)
[ 988.595045][ T43] ? entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.595164][ T43] mutex_lock (kernel/locking/mutex.c:286)
[ 988.595246][ T43] ? __mutex_lock_slowpath (kernel/locking/mutex.c:282)
[ 988.595343][ T43] ? down_read (arch/x86/include/asm/atomic64_64.h:34 include/linux/atomic/atomic-long.h:41 include/linux/atomic/atomic-instrumented.h:1280 kernel/locking/rwsem.c:171 kernel/locking/rwsem.c:176 kernel/locking/rwsem.c:244 kernel/locking/rwsem.c:1241 kernel/locking/rwsem.c:1251 kernel/locking/rwsem.c:1491)
[ 988.595426][ T43] lock_rename (fs/namei.c:3015)
[ 988.595508][ T43] do_renameat2 (fs/namei.c:4813)
[ 988.595593][ T43] ? __x64_sys_link (fs/namei.c:4760)
[ 988.595679][ T43] ? check_heap_object (arch/x86/include/asm/bitops.h:207 include/asm-generic/bitops/instrumented-non-atomic.h:135 include/linux/page-flags.h:506 mm/usercopy.c:193)
[ 988.595771][ T43] ? __check_object_size (mm/memremap.c:37)
[ 988.595875][ T43] ? strncpy_from_user (arch/x86/include/asm/uaccess.h:542 lib/strncpy_from_user.c:138)
[ 988.595991][ T43] ? getname_flags (fs/namei.c:205)
[ 988.596104][ T43] __x64_sys_renameat2 (fs/namei.c:4900)
[ 988.596213][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.596297][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.596404][ T43] RIP: 0033:0x7fcab427bf3f
[ 988.596487][ T43] RSP: 002b:00007ffc5e54ca18 EFLAGS: 00000202 ORIG_RAX: 000000000000013c
[ 988.596633][ T43] RAX: ffffffffffffffda RBX: 00007ffc5e54cce0 RCX: 00007fcab427bf3f
[ 988.596771][ T43] RDX: 00000000ffffff9c RSI: 0000555ea31b93c0 RDI: 00000000ffffff9c
[ 988.596925][ T43] RBP: 00007ffc5e54ccf0 R08: 0000000000000002 R09: 0000000000000003
[ 988.597084][ T43] R10: 0000555ea31b8d70 R11: 0000000000000202 R12: 0000000000000002
[ 988.597224][ T43] R13: 00007ffc5e54cce0 R14: 0000000000000002 R15: 00007ffc5e54ccc4
[ 988.597362][ T43] </TASK>
[ 988.597422][ T43] INFO: task fsstress:4726 blocked for more than 491 seconds.
[ 988.597551][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.597691][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.597838][ T43] task:fsstress state:D stack: 0 pid: 4726 ppid: 4715 flags:0x00004000
[ 988.598019][ T43] Call Trace:
[ 988.598096][ T43] <TASK>
[ 988.598166][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.598260][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.598356][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.598454][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.598533][ T43] schedule_timeout (kernel/time/timer.c:1912)
[ 988.598624][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.598720][ T43] ? _raw_spin_lock_irq (arch/x86/include/asm/atomic.h:202 include/linux/atomic/atomic-instrumented.h:543 include/asm-generic/qspinlock.h:111 include/linux/spinlock.h:185 include/linux/spinlock_api_smp.h:120 kernel/locking/spinlock.c:170)
[ 988.598811][ T43] ? _raw_spin_lock_bh (kernel/locking/spinlock.c:169)
[ 988.598904][ T43] __wait_for_common (kernel/sched/completion.c:86 kernel/sched/completion.c:106)
[ 988.599019][ T43] ? usleep_range_state (kernel/time/timer.c:1897)
[ 988.599117][ T43] ? out_of_line_wait_on_bit_timeout (kernel/sched/completion.c:100)
[ 988.599230][ T43] ? mutex_unlock (arch/x86/include/asm/atomic64_64.h:190 include/linux/atomic/atomic-long.h:449 include/linux/atomic/atomic-instrumented.h:1790 kernel/locking/mutex.c:181 kernel/locking/mutex.c:540)
[ 988.599315][ T43] ? __mutex_unlock_slowpath+0x2c0/0x2c0
[ 988.599433][ T43] __flush_workqueue (kernel/workqueue.c:2869)
[ 988.599526][ T43] ? flush_workqueue_prep_pwqs (kernel/workqueue.c:2798)
[ 988.599629][ T43] ? cpumask_next (lib/cpumask.c:23)
[ 988.599713][ T43] ? xfs_inodegc_queue_all (fs/xfs/xfs_icache.c:442 (discriminator 1)) xfs
[ 988.599981][ T43] xfs_trans_alloc (include/linux/slab.h:723 fs/xfs/xfs_trans.c:261) xfs
[ 988.600268][ T43] xfs_trans_alloc_inode (fs/xfs/xfs_trans.c:1194) xfs
[ 988.600497][ T43] ? xfs_trans_roll (fs/xfs/xfs_trans.c:1184) xfs
[ 988.600716][ T43] xfs_alloc_file_space (fs/xfs/xfs_bmap_util.c:859) xfs
[ 988.600955][ T43] ? xfs_free_eofblocks (fs/xfs/xfs_bmap_util.c:777) xfs
[ 988.601220][ T43] ? xfs_prepare_shift (fs/xfs/xfs_bmap_util.c:969) xfs
[ 988.601436][ T43] xfs_file_fallocate (fs/xfs/xfs_file.c:1033) xfs
[ 988.601651][ T43] ? xfs_break_layouts (fs/xfs/xfs_file.c:886) xfs
[ 988.601867][ T43] ? __do_sys_newfstat (fs/stat.c:438)
[ 988.601984][ T43] ? __ia32_sys_fstat (fs/stat.c:433)
[ 988.602091][ T43] ? do_rmdir (fs/namei.c:4080)
[ 988.602181][ T43] vfs_fallocate (fs/open.c:323)
[ 988.602267][ T43] __x64_sys_fallocate (include/linux/file.h:44 fs/open.c:347 fs/open.c:354 fs/open.c:352 fs/open.c:352)
[ 988.602359][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.602441][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.602547][ T43] RIP: 0033:0x7fcab4317cd6
[ 988.602630][ T43] RSP: 002b:00007ffc5e54c838 EFLAGS: 00000246 ORIG_RAX: 000000000000011d
[ 988.602775][ T43] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 00007fcab4317cd6
[ 988.602939][ T43] RDX: 00000000002af3b1 RSI: 0000000000000011 RDI: 0000000000000004
[ 988.603097][ T43] RBP: 0000000000000004 R08: 0000000000000002 R09: 0000000000000003
[ 988.603241][ T43] R10: 00000000000ca940 R11: 0000000000000246 R12: 00000000000000cd
[ 988.603379][ T43] R13: 00000000000ca940 R14: 00000000002af3b1 R15: 0000000000000011
[ 988.603517][ T43] </TASK>
[ 988.603577][ T43] INFO: task fsstress:4728 blocked for more than 491 seconds.
[ 988.603707][ T43] Tainted: G S 5.19.0-rc5-00072-gd6c6912258aa #1
[ 988.603846][ T43] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 988.604015][ T43] task:fsstress state:D stack: 0 pid: 4728 ppid: 4715 flags:0x00000000
[ 988.604191][ T43] Call Trace:
[ 988.604255][ T43] <TASK>
[ 988.604314][ T43] __schedule (kernel/sched/core.c:5146 kernel/sched/core.c:6458)
[ 988.604397][ T43] ? io_schedule_timeout (kernel/sched/core.c:6340)
[ 988.604495][ T43] ? __legitimize_path (fs/namei.c:699)
[ 988.604588][ T43] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2196 (discriminator 1) kernel/sched/core.c:6532 (discriminator 1))
[ 988.604664][ T43] schedule_preempt_disabled (arch/x86/include/asm/preempt.h:80 kernel/sched/core.c:6590)
[ 988.604764][ T43] __mutex_lock+0x85c/0x1040
[ 988.604867][ T43] ? path_parentat (fs/namei.c:2556)
[ 988.604975][ T43] ? ww_mutex_lock (kernel/locking/mutex.c:744)
[ 988.605082][ T43] ? stack_trace_save (kernel/stacktrace.c:123)
[ 988.605185][ T43] ? get_page_from_freelist (mm/page_alloc.c:2459 mm/page_alloc.c:4198)
[ 988.605286][ T43] mutex_lock (kernel/locking/mutex.c:286)
[ 988.605368][ T43] ? __mutex_lock_slowpath (kernel/locking/mutex.c:282)
[ 988.605465][ T43] lock_rename (fs/namei.c:3015)
[ 988.605546][ T43] do_renameat2 (fs/namei.c:4813)
[ 988.605631][ T43] ? __kasan_init_slab_obj (mm/kasan/common.c:326)
[ 988.605727][ T43] ? __x64_sys_link (fs/namei.c:4760)
[ 988.605815][ T43] ? allocate_slab (mm/slub.c:378 mm/slub.c:1929 mm/slub.c:1995)
[ 988.605903][ T43] ? check_heap_object (arch/x86/include/asm/bitops.h:207 include/asm-generic/bitops/instrumented-non-atomic.h:135 include/linux/page-flags.h:506 mm/usercopy.c:193)
[ 988.606031][ T43] ? __check_object_size (mm/memremap.c:37)
[ 988.606154][ T43] ? strncpy_from_user (arch/x86/include/asm/uaccess.h:542 lib/strncpy_from_user.c:138)
[ 988.606253][ T43] ? getname_flags (fs/namei.c:205)
[ 988.606351][ T43] __x64_sys_renameat2 (fs/namei.c:4900)
[ 988.606443][ T43] do_syscall_64 (arch/x86/entry/common.c:50 arch/x86/entry/common.c:80)
[ 988.606525][ T43] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:115)
[ 988.606630][ T43] RIP: 0033:0x7fcab427bf3f
[ 988.606712][ T43] RSP: 002b:00007ffc5e54ca18 EFLAGS: 00000202 ORIG_RAX: 000000000000013c
[ 988.606858][ T43] RAX: ffffffffffffffda RBX: 00007ffc5e54cce0 RCX: 00007fcab427bf3f
[ 988.607020][ T43] RDX: 00000000ffffff9c RSI: 0000555ea31b8ee0 RDI: 00000000ffffff9c
[ 988.607175][ T43] RBP: 00007ffc5e54ccf0 R08: 0000000000000004 R09: 0000000000000003
[ 988.607318][ T43] R10: 0000555ea31b8a10 R11: 0000000000000202 R12: 0000000000000004
[ 988.607454][ T43] R13: 00007ffc5e54cce0 R14: 0000000000000013 R15: 00007ffc5e54ccc4
[ 988.607592][ T43] </TASK>


To reproduce:

git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file

# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.



--
0-DAY CI Kernel Test Service
https://01.org/lkp




Attachments:
(No filename) (42.30 kB)
config-5.19.0-rc5-00072-gd6c6912258aa (170.14 kB)
config-5.19.0-rc5-00072-gd6c6912258aa
job-script (5.90 kB)
job-script
dmesg.xz (9.20 kB)
dmesg.xz
xfstests (912.00 B)
xfstests
job.yaml (4.67 kB)
job.yaml
reproduce (961.00 B)
reproduce
Download all attachments