lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
Date:   Sat, 23 Jul 2022 17:34:37 +0800
From:   kernel test robot <oliver.sang@...el.com>
To:     Allison Henderson <allison.henderson@...cle.com>
CC:     0day robot <lkp@...el.com>, LKML <linux-kernel@...r.kernel.org>,
        <linux-xfs@...r.kernel.org>, <lkp@...ts.01.org>
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/20220718202022.6598-16-allison.henderson@oracle.com

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 <oliver.sang@...el.com>


[  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


.

View attachment "config-5.19.0-rc5-00072-gd6c6912258aa" of type "text/plain" (174225 bytes)

View attachment "job-script" of type "text/plain" (6046 bytes)

Download attachment "dmesg.xz" of type "application/x-xz" (9420 bytes)

View attachment "xfstests" of type "text/plain" (912 bytes)

View attachment "job.yaml" of type "text/plain" (4780 bytes)

View attachment "reproduce" of type "text/plain" (961 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ