************************* hanginfo ************************* /exports/testreports/42112/testresults/racer-ldiskfs-DNE-centos7_x86_64-centos7_x86_64/oleg224-client-timeout-core (3.10.0-7.9-debug) *** UNINTERRUPTIBLE threads, classified *** ==================== Waiting on mutexes ==================== -- lustre_inode_cache Owner: pid=18946 cmd=lfs ... 8 pids. Youngest,oldest: 19027, 18949 Ran ms ago: 1, 167 sorted by ran_ago, youngest first [19027, 18997, 18890, 18973, 18961, 18960, 18409, 18949] -- lustre_inode_cache Owner: pid=18951 cmd=mrename ... 10 pids. Youngest,oldest: 19018, 18958 Ran ms ago: 12, 139 sorted by ran_ago, youngest first [19018, 19005, 18999, 18990, 18989, 18985, 18974, 18966, 18962, 18958] -- lustre_inode_cache Owner: pid=18988 cmd=mrename ... 10 pids. Youngest,oldest: 19023, 18993 Ran ms ago: 8, 67 sorted by ran_ago, youngest first [19023, 19022, 19019, 18678, 18888, 19012, 19016, 19008, 19003, 18993] -- lustre_inode_cache Owner: pid=18980 cmd=mrename ... 7 pids. Youngest,oldest: 19007, 18981 Ran ms ago: 40, 100 sorted by ran_ago, youngest first [19007, 19001, 19004, 19000, 18929, 18984, 18981] -- kmalloc-2048 Owner: pid=18888 cmd=mrename ... 2 pids. Youngest,oldest: 18906, 18804 Ran ms ago: 316, 340 sorted by ran_ago, youngest first [18906, 18804] -- kmalloc-2048 Owner: pid=18409 cmd=mrename ... 4 pids. Youngest,oldest: 19017, 18693 Ran ms ago: 13, 727 sorted by ran_ago, youngest first [19017, 18884, 18886, 18693] *** Threads that own resources the other threads are waiting on *** PID=18946 CPU=2 CMD=lfs #0 __schedule+0x2e2 #1 schedule+0x29 #2 schedule_timeout+0x13f #3 ptlrpc_set_wait+0x7cf #4 ptlrpc_queue_wait+0x86 #5 ldlm_cli_enqueue+0x424 #6 mdc_enqueue_base+0x37a #7 mdc_intent_lock+0x135 #8 lmv_intent_remote+0x5de #9 lmv_intent_open+0xb70 #10 lmv_intent_lock+0x1e7 #11 ll_lookup_it.constprop.42+0x727 #12 ll_atomic_open+0x283 #13 do_last+0xa40 #14 path_openat+0xcd #15 do_filp_open+0x4d #16 do_sys_open+0x124 #17 sys_open+0x1e #18 system_call_fastpath+0x1f, 477 bytes of data {18949, 18409, 18890, 18960, 18961, 19027, 18997, 18973} PID=18980 CPU=2 CMD=mrename #0 __schedule+0x2e2 #1 schedule+0x29 #2 schedule_timeout+0x13f #3 ptlrpc_set_wait+0x7cf #4 ptlrpc_queue_wait+0x86 #5 mdc_reint+0x22 #6 mdc_rename+0x32c #7 lmv_rename+0x5ac #8 ll_rename+0x560 #9 vfs_rename+0x885 #10 SYSC_renameat2+0x4d3 #11 sys_renameat2+0xe #12 sys_rename+0x1e #13 system_call_fastpath+0x1f, 477 bytes of data {18929, 18981, 18984, 19001, 19000, 19004, 19007} PID=18951 CPU=0 CMD=mrename #0 __schedule+0x2e2 #1 schedule+0x29 #2 schedule_timeout+0x13f #3 ptlrpc_set_wait+0x7cf #4 ptlrpc_queue_wait+0x86 #5 ldlm_cli_enqueue+0x424 #6 mdc_enqueue_base+0x37a #7 mdc_intent_lock+0x135 #8 lmv_intent_remote+0x5de #9 lmv_intent_lookup+0x9f9 #10 lmv_intent_lock+0x26f #11 ll_lookup_it.constprop.42+0x727 #12 ll_lookup_nd+0x110 #13 lookup_real+0x1d #14 __lookup_hash+0x42 #15 SYSC_renameat2+0x3a9 #16 sys_renameat2+0xe #17 sys_rename+0x1e #18 system_call_fastpath+0x1f, 477 bytes of data {18985, 19018, 18989, 18958, 18990, 18962, 18966, 18999, 19005, 18974} PID=18888 CPU=2 CMD=mrename #0 __schedule+0x2e2 #1 schedule_preempt_disabled+0x39 #2 __mutex_lock_slowpath+0x13a #3 mutex_lock+0x2d #4 lock_rename+0x93 #5 SYSC_renameat2+0x22f #6 sys_renameat2+0xe #7 sys_rename+0x1e #8 system_call_fastpath+0x1f, 477 bytes of data {18906, 18804} PID=18409 CPU=2 CMD=mrename #0 __schedule+0x2e2 #1 schedule_preempt_disabled+0x39 #2 __mutex_lock_slowpath+0x13a #3 mutex_lock+0x2d #4 lock_rename+0x55 #5 SYSC_renameat2+0x22f #6 sys_renameat2+0xe #7 sys_rename+0x1e #8 system_call_fastpath+0x1f, 477 bytes of data {19017, 18884, 18693, 18886} PID=18988 CPU=2 CMD=mrename #0 __schedule+0x2e2 #1 schedule+0x29 #2 schedule_timeout+0x13f #3 ptlrpc_set_wait+0x7cf #4 ptlrpc_queue_wait+0x86 #5 mdc_reint+0x22 #6 mdc_rename+0x32c #7 lmv_rename+0x5ac #8 ll_rename+0x560 #9 vfs_rename+0x885 #10 SYSC_renameat2+0x4d3 #11 sys_renameat2+0xe #12 sys_rename+0x1e #13 system_call_fastpath+0x1f, 477 bytes of data {19008, 19012, 19016, 18888, 19019, 19022, 19023, 18993, 18678, 19003} ** Execution took 1.98s (real) 1.50s (CPU), Child processes: 0.49s