00000100:00080000:1.0F:1715205923.567892:0:65:0:(pinger.c:253:ptlrpc_pinger_process_import()) 4f93b71f-777f-44ae-ae8f-53d858e20398->lustre-MDT0000_UUID: level EVICTED/10 force 0 force_next 0 deactive 0 pingable 1 suppress 0
00000100:00080000:1.0:1715205923.567902:0:65:0:(pinger.c:271:ptlrpc_pinger_process_import()) 4f93b71f-777f-44ae-ae8f-53d858e20398->lustre-MDT0000_UUID: not pinging (in recovery or recovery disabled: EVICTED)
00000100:00100000:1.0:1715205923.567947:0:2005:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa028380 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:4f93b71f-777f-44ae-ae8f-53d858e20398:2005:1798523513242240:192.168.204.135@tcp:400:kworker.0
00000100:00100000:0.0F:1715205923.567952:0:2004:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa02b480 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2004:1798523513242176:192.168.204.135@tcp:400:kworker.0
00000100:00100000:0.0:1715205923.568014:0:2004:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa02b100 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2004:1798523513242304:192.168.204.135@tcp:400:kworker.0
00000100:00100000:1.0:1715205923.568016:0:2005:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa02ad80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2005:1798523513242368:192.168.204.135@tcp:400:kworker.0
00000100:00100000:0.0:1715205923.568027:0:2004:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa028000 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2004:1798523513242432:192.168.204.135@tcp:400:kworker.0
00000100:00100000:1.0:1715205923.568036:0:2005:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa02b800 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2005:1798523513242496:192.168.204.135@tcp:400:kworker.0
00000100:00100000:0.0:1715205923.569040:0:2004:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa02b480 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2004:1798523513242176:192.168.204.135@tcp:400:kworker.0
00010000:00080000:1.0:1715205923.569048:0:2005:0:(ldlm_request.c:1540:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46624)  req@ffff8800aa028380 x1798523513242240/t0(0) o400->lustre-MDT0001-mdc-ffff8800b3e33000@192.168.204.135@tcp:12/10 lens 224/224 e 0 to 0 dl 1715205939 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'kworker.0' uid:0 gid:0
00000100:00100000:0.0:1715205923.569050:0:2004:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa02b100 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2004:1798523513242304:192.168.204.135@tcp:400:kworker.0
00010000:00080000:0.0:1715205923.569056:0:2004:0:(ldlm_request.c:1540:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46624)  req@ffff8800aa028000 x1798523513242432/t0(0) o400->lustre-OST0000-osc-ffff8800a9d1f000@192.168.204.135@tcp:28/4 lens 224/224 e 0 to 0 dl 1715205939 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'kworker.0' uid:0 gid:0
00000100:00100000:1.0:1715205923.569057:0:2005:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa028380 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:4f93b71f-777f-44ae-ae8f-53d858e20398:2005:1798523513242240:192.168.204.135@tcp:400:kworker.0
00000100:00100000:0.0:1715205923.569061:0:2004:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa028000 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2004:1798523513242432:192.168.204.135@tcp:400:kworker.0
00000100:00100000:1.0:1715205923.569063:0:2005:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa02b800 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2005:1798523513242496:192.168.204.135@tcp:400:kworker.0
00010000:00080000:1.0:1715205923.569073:0:2005:0:(ldlm_request.c:1540:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46624)  req@ffff8800aa02ad80 x1798523513242368/t0(0) o400->lustre-MDT0001-mdc-ffff8800a9d1f000@192.168.204.135@tcp:12/10 lens 224/224 e 0 to 0 dl 1715205939 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'kworker.0' uid:0 gid:0
00000100:00100000:1.0:1715205923.569078:0:2005:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa02ad80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:2005:1798523513242368:192.168.204.135@tcp:400:kworker.0
00010000:00010000:1.0:1715205923.825892:0:5876:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000080:00200008:1.0:1715205923.864481:0:7497:0:(file.c:5701:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff8800b0a680c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:1.0:1715205923.864489:0:7497:0:(file.c:5598:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff8800b0a680c8),name=/
00000002:00010000:1.0:1715205923.864508:0:7497:0:(mdc_locks.c:1426:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:1.0:1715205923.864551:0:7497:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x0/0x0 rrc: 2 type: IBT flags: 0x10000000000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864560:0:7497:0:(ldlm_request.c:1038:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000 ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864568:0:7497:0:(ldlm_request.c:1125:ldlm_cli_enqueue()) ### sending request ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 7497 initiator: MDT0
00000100:00100000:1.0:1715205923.864589:0:7497:0:(client.c:2542:ptlrpc_set_wait()) set ffff88012dbc1780 going to sleep for 0 seconds
00000100:00100000:1.0:1715205923.864598:0:7497:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa02ad80 pname:cluuid:pid:xid:nid:opc:job bash:4f93b71f-777f-44ae-ae8f-53d858e20398:7497:1798523513242560:192.168.204.135@tcp:101:bash.0
00010000:00010000:1.0:1715205923.864605:0:7497:0:(ldlm_request.c:655:ldlm_cli_enqueue_fini()) ### client-side enqueue END (FAILED) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864610:0:7497:0:(ldlm_request.c:572:failed_lock_cleanup()) ### setting FL_LOCAL_ONLY | LDLM_FL_FAILED | LDLM_FL_ATOMIC_CB | LDLM_FL_CBPENDING ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864615:0:7497:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864619:0:7497:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864624:0:7497:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864629:0:7497:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa930480 already unused, calling callback (ffffffffa1047e10)
00010000:00010000:1.0:1715205923.864632:0:7497:0:(ldlm_request.c:1599:ldlm_cli_convert()) ### client lock convert START ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864636:0:7497:0:(ldlm_request.c:1609:ldlm_cli_convert()) ### client lock convert END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864641:0:7497:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 5/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x20e400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864645:0:7497:0:(ldlm_request.c:1316:ldlm_cli_cancel_local()) not sending request (at caller's instruction)
00010000:00010000:1.0:1715205923.864650:0:7497:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 7497 initiator: MDT0
00010000:00010000:1.0:1715205923.864654:0:7497:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa930480/0x68b86d4dd46f506b lrc: 0/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 7497 initiator: MDT0
00000080:00020000:1.0:1715205923.864675:0:7497:0:(file.c:5575:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108
00000080:00200008:3.0F:1715205923.907661:0:21797:0:(file.c:5701:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff8800b0a680c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:3.0:1715205923.907673:0:21797:0:(file.c:5598:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff8800b0a680c8),name=/
00000002:00010000:3.0:1715205923.907693:0:21797:0:(mdc_locks.c:1426:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:3.0:1715205923.907742:0:21797:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x0/0x0 rrc: 2 type: IBT flags: 0x10000000000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907750:0:21797:0:(ldlm_request.c:1038:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000 ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907758:0:21797:0:(ldlm_request.c:1125:ldlm_cli_enqueue()) ### sending request ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00000100:00100000:3.0:1715205923.907774:0:21797:0:(client.c:2542:ptlrpc_set_wait()) set ffff8800abfab180 going to sleep for 0 seconds
00000100:00100000:3.0:1715205923.907784:0:21797:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff88012d7e7b80 pname:cluuid:pid:xid:nid:opc:job lsof:4f93b71f-777f-44ae-ae8f-53d858e20398:21797:1798523513242624:192.168.204.135@tcp:101:lsof.0
00010000:00010000:3.0:1715205923.907793:0:21797:0:(ldlm_request.c:655:ldlm_cli_enqueue_fini()) ### client-side enqueue END (FAILED) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907799:0:21797:0:(ldlm_request.c:572:failed_lock_cleanup()) ### setting FL_LOCAL_ONLY | LDLM_FL_FAILED | LDLM_FL_ATOMIC_CB | LDLM_FL_CBPENDING ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907822:0:21797:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907827:0:21797:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907832:0:21797:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907836:0:21797:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa95ef40 already unused, calling callback (ffffffffa1047e10)
00010000:00010000:3.0:1715205923.907839:0:21797:0:(ldlm_request.c:1599:ldlm_cli_convert()) ### client lock convert START ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907844:0:21797:0:(ldlm_request.c:1609:ldlm_cli_convert()) ### client lock convert END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907854:0:21797:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 5/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x20e400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907859:0:21797:0:(ldlm_request.c:1316:ldlm_cli_cancel_local()) not sending request (at caller's instruction)
00010000:00010000:3.0:1715205923.907863:0:21797:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.907867:0:21797:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa95ef40/0x68b86d4dd46f5072 lrc: 0/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 21797 initiator: MDT0
00000080:00020000:3.0:1715205923.907884:0:21797:0:(file.c:5575:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108
00000080:00200008:3.0:1715205923.908314:0:21797:0:(file.c:5701:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff88012f2688c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:3.0:1715205923.908319:0:21797:0:(file.c:5598:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff88012f2688c8),name=/
00000002:00010000:3.0:1715205923.908330:0:21797:0:(mdc_locks.c:1426:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:3.0:1715205923.908350:0:21797:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x0/0x0 rrc: 2 type: IBT flags: 0x10000000000000 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.908356:0:21797:0:(ldlm_request.c:1038:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000 ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00010000:00010000:3.0:1715205923.908361:0:21797:0:(ldlm_request.c:1125:ldlm_cli_enqueue()) ### sending request ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00000100:00100000:3.0:1715205923.908373:0:21797:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff88012d7e7b80 pname:cluuid:pid:xid:nid:opc:job lsof:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:21797:1798523513242688:192.168.204.135@tcp:101:lsof.0
00000100:00100000:3.0:1715205923.908440:0:21797:0:(client.c:2542:ptlrpc_set_wait()) set ffff8800abfab180 going to sleep for 11 seconds
00000100:00100000:0.0:1715205923.909706:0:21797:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff88012d7e7b80 pname:cluuid:pid:xid:nid:opc:job lsof:6996bfa2-24ab-4f77-8c1c-5aaba286a5f7:21797:1798523513242688:192.168.204.135@tcp:101:lsof.0
00010000:00010000:0.0:1715205923.909717:0:21797:0:(ldlm_request.c:726:ldlm_cli_enqueue_fini()) ### server returned different mode PR ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909724:0:21797:0:(ldlm_request.c:754:ldlm_cli_enqueue_fini()) ### client-side enqueue, new policy data ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 4/1,0 mode: --/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909732:0:21797:0:(ldlm_lock.c:1089:ldlm_granted_list_add_lock()) ### About to add lock: ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 4/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x10000000000000 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909741:0:21797:0:(ldlm_request.c:808:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 4/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909747:0:21797:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(PR) ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 5/2,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x10000000000000 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909753:0:21797:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 5/2,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x10000000000000 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909762:0:21797:0:(ldlm_lock.c:936:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 4/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x10000000000000 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00000002:00100000:0.0:1715205923.909771:0:21797:0:(mdc_locks.c:838:mdc_finish_enqueue()) @@@ op=8 disposition=b, status=0  req@ffff88012d7e7b80 x1798523513242688/t0(0) o101->lustre-MDT0000-mdc-ffff8800a9d1f000@192.168.204.135@tcp:12/10 lens 576/688 e 0 to 0 dl 1715205939 ref 1 fl Complete:RQU/200/0 rc 0/0 job:'lsof.0' uid:0 gid:0
00000002:00010000:0.0:1715205923.909781:0:21797:0:(mdc_locks.c:1277:mdc_finish_intent_lock()) ### matching against this ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 3/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909790:0:21797:0:(ldlm_lock.c:1559:ldlm_lock_match_with_skip()) ### not matched ns ffff8800aa97f800 type 13 mode 4 res 8589934599/1 (0 0)
00000080:00200000:0.0:1715205923.909823:0:21797:0:(llite_lib.c:2872:ll_update_inode()) inode=[0x200000007:0x1:0x0], updating i_size 4096
00000080:00010000:0.0:1715205923.909827:0:21797:0:(llite_internal.h:1965:ll_set_lock_data()) setting l_data to inode [0x200000007:0x1:0x0](ffff88012f2688c8) for lock 0x68b86d4dd46f5079
00000080:00010000:0.0:1715205923.909833:0:21797:0:(dcache.c:301:ll_lookup_finish_locks()) setting l_data to inode [0x200000007:0x1:0x0](ffff88012f2688c8)
00000080:00010000:0.0:1715205923.909835:0:21797:0:(dcache.c:194:ll_intent_drop_lock()) releasing lock with cookie 0x68b86d4dd46f5079 from it ffff8800b3f13cc8
00010000:00010000:0.0:1715205923.909837:0:21797:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(PR) ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 3/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x10000000000000 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.909843:0:21797:0:(ldlm_lock.c:929:ldlm_lock_decref_internal()) ### add lock into lru list ns: lustre-MDT0000-mdc-ffff8800a9d1f000 lock: ffff8800aa95e400/0x68b86d4dd46f5079 lrc: 2/0,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x4cb6a6a97781156a expref: -99 pid: 21797 timeout: 0 lvb_type: 0
00000080:00200008:0.0:1715205923.910013:0:21797:0:(file.c:5701:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff8800b0a680c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:0.0:1715205923.910018:0:21797:0:(file.c:5598:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff8800b0a680c8),name=/
00000002:00010000:0.0:1715205923.910025:0:21797:0:(mdc_locks.c:1426:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:0.0:1715205923.910045:0:21797:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x0/0x0 rrc: 2 type: IBT flags: 0x10000000000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910050:0:21797:0:(ldlm_request.c:1038:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000 ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910064:0:21797:0:(ldlm_request.c:1125:ldlm_cli_enqueue()) ### sending request ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00000100:00100000:0.0:1715205923.910074:0:21797:0:(client.c:2542:ptlrpc_set_wait()) set ffff8800a9fe3540 going to sleep for 0 seconds
00000100:00100000:0.0:1715205923.910078:0:21797:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa973100 pname:cluuid:pid:xid:nid:opc:job lsof:4f93b71f-777f-44ae-ae8f-53d858e20398:21797:1798523513242752:192.168.204.135@tcp:101:lsof.0
00010000:00010000:0.0:1715205923.910084:0:21797:0:(ldlm_request.c:655:ldlm_cli_enqueue_fini()) ### client-side enqueue END (FAILED) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910088:0:21797:0:(ldlm_request.c:572:failed_lock_cleanup()) ### setting FL_LOCAL_ONLY | LDLM_FL_FAILED | LDLM_FL_ATOMIC_CB | LDLM_FL_CBPENDING ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910093:0:21797:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910097:0:21797:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910101:0:21797:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910106:0:21797:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa976f40 already unused, calling callback (ffffffffa1047e10)
00010000:00010000:0.0:1715205923.910108:0:21797:0:(ldlm_request.c:1599:ldlm_cli_convert()) ### client lock convert START ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910113:0:21797:0:(ldlm_request.c:1609:ldlm_cli_convert()) ### client lock convert END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910117:0:21797:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 5/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x20e400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910122:0:21797:0:(ldlm_request.c:1316:ldlm_cli_cancel_local()) not sending request (at caller's instruction)
00010000:00010000:0.0:1715205923.910124:0:21797:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 21797 initiator: MDT0
00010000:00010000:0.0:1715205923.910132:0:21797:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa976f40/0x68b86d4dd46f5080 lrc: 0/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 21797 initiator: MDT0
00000080:00020000:0.0:1715205923.910147:0:21797:0:(file.c:5575:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108
00000080:00200008:2.0F:1715205923.914178:0:21798:0:(file.c:5701:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff8800b0a680c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:2.0:1715205923.914187:0:21798:0:(file.c:5598:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff8800b0a680c8),name=/
00000002:00010000:2.0:1715205923.914203:0:21798:0:(mdc_locks.c:1426:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:2.0:1715205923.914237:0:21798:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x0/0x0 rrc: 2 type: IBT flags: 0x10000000000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914242:0:21798:0:(ldlm_request.c:1038:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000 ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914246:0:21798:0:(ldlm_request.c:1125:ldlm_cli_enqueue()) ### sending request ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21798 initiator: MDT0
00000100:00100000:2.0:1715205923.914257:0:21798:0:(client.c:2542:ptlrpc_set_wait()) set ffff88012e15b6c0 going to sleep for 0 seconds
00000100:00100000:2.0:1715205923.914263:0:21798:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa935180 pname:cluuid:pid:xid:nid:opc:job umount:4f93b71f-777f-44ae-ae8f-53d858e20398:21798:1798523513242816:192.168.204.135@tcp:101:umount.0
00010000:00010000:2.0:1715205923.914269:0:21798:0:(ldlm_request.c:655:ldlm_cli_enqueue_fini()) ### client-side enqueue END (FAILED) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914273:0:21798:0:(ldlm_request.c:572:failed_lock_cleanup()) ### setting FL_LOCAL_ONLY | LDLM_FL_FAILED | LDLM_FL_ATOMIC_CB | LDLM_FL_CBPENDING ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914276:0:21798:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 4/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914280:0:21798:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x10206400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914283:0:21798:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x206400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914286:0:21798:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa964900 already unused, calling callback (ffffffffa1047e10)
00010000:00010000:2.0:1715205923.914288:0:21798:0:(ldlm_request.c:1599:ldlm_cli_convert()) ### client lock convert START ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914291:0:21798:0:(ldlm_request.c:1609:ldlm_cli_convert()) ### client lock convert END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 4/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x206400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914298:0:21798:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 5/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x20e400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914301:0:21798:0:(ldlm_request.c:1316:ldlm_cli_cancel_local()) not sending request (at caller's instruction)
00010000:00010000:2.0:1715205923.914304:0:21798:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 3/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 21798 initiator: MDT0
00010000:00010000:2.0:1715205923.914307:0:21798:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: lustre-MDT0000-mdc-ffff8800b3e33000 lock: ffff8800aa964900/0x68b86d4dd46f5087 lrc: 0/0,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x2 rrc: 2 type: IBT flags: 0x4a0f400000000 pid: 21798 initiator: MDT0
00000080:00020000:2.0:1715205923.914320:0:21798:0:(file.c:5575:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108
00000080:00200000:2.0:1715205923.927068:0:21798:0:(llite_lib.c:1925:ll_clear_inode()) VFS Op:inode=[0x200000007:0x1:0x0](ffff8800b0a680c8)
00000080:00200000:2.0:1715205923.927091:0:21798:0:(llite_lib.c:1551:ll_put_super()) VFS Op: cfg_instance lustre-client-ffff8800b3e33000 (sb ffff8800b3e33000)
00010000:00010000:2.0:1715205923.927106:0:21798:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x11000000000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927114:0:21798:0:(ldlm_lock.c:971:ldlm_lock_decref_and_cancel()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x1000000000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927117:0:21798:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x11000400000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927120:0:21798:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x11000400000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927134:0:21798:0:(ldlm_lockd.c:2227:__ldlm_bl_to_thread()) added 1/1 locks to regular blp list, 1 blwis in pool
00010000:00010000:2.0:1715205923.927137:0:21798:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927139:0:21798:0:(ldlm_lock.c:971:ldlm_lock_decref_and_cancel()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x1000000000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927164:0:21798:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x11000400000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927167:0:21798:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x11000400000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927177:0:21798:0:(ldlm_lockd.c:2227:__ldlm_bl_to_thread()) added 1/2 locks to regular blp list, 2 blwis in pool
00010000:00010000:2.0:1715205923.927180:0:21798:0:(ldlm_lock.c:791:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 3/1,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927183:0:5365:0:(ldlm_lockd.c:2857:ldlm_bl_get_work()) Got 1 locks of 1 total in blp.  (1 blwis in pool)
00010000:00010000:2.0:1715205923.927183:0:21798:0:(ldlm_lock.c:971:ldlm_lock_decref_and_cancel()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 3/1,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x1000000000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927186:0:21798:0:(ldlm_lock.c:845:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 3/1,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x11000400000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927188:0:21798:0:(ldlm_lock.c:906:ldlm_lock_decref_internal()) ### final decref done on CBPENDING lock ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 2/0,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x11000400000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927189:0:5365:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x1000400000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:2.0:1715205923.927194:0:21798:0:(ldlm_lockd.c:2227:__ldlm_bl_to_thread()) added 1/2 locks to regular blp list, 2 blwis in pool
00010000:00010000:0.0:1715205923.927196:0:5365:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa964480 already unused, calling callback (ffffffffa04cea40)
10000000:01000000:2.0:1715205923.927196:0:21798:0:(mgc_request.c:562:config_log_end()) end config log lustre-client (0)
10000000:00010000:0.0:1715205923.927199:0:5365:0:(mgc_request.c:918:mgc_blocking_ast()) ### MGC blocking CB ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x1000400000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927205:0:5365:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x1008400000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
10000000:00010000:0.0:1715205923.927211:0:5365:0:(mgc_request.c:924:mgc_blocking_ast()) ### MGC cancel CB ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x1009400000000 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
10000000:01000000:0.0:1715205923.927215:0:5365:0:(mgc_request.c:928:mgc_blocking_ast()) Lock res [0x65727473756c:0x0:0x0].0x0 (lustre)
10000000:01000000:0.0:1715205923.927217:0:5365:0:(mgc_request.c:940:mgc_blocking_ast()) log lustre-client: stopping, won't requeue
10000000:01000000:0.0:1715205923.927219:0:5365:0:(mgc_request.c:150:config_log_put()) dropping config log lustre-client
00010000:00010000:1.0:1715205923.927230:0:16624:0:(ldlm_lockd.c:2857:ldlm_bl_get_work()) Got 1 locks of 1 total in blp.  (1 blwis in pool)
00010000:00010000:1.0:1715205923.927235:0:16624:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x1000400000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927240:0:16624:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa974240 already unused, calling callback (ffffffffa04cea40)
10000000:00010000:1.0:1715205923.927241:0:16624:0:(mgc_request.c:918:mgc_blocking_ast()) ### MGC blocking CB ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x1000400000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927245:0:16624:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x1008400000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927246:0:5365:0:(ldlm_request.c:1347:__ldlm_pack_lock()) ### packing ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 2/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
10000000:00010000:1.0:1715205923.927248:0:16624:0:(mgc_request.c:924:mgc_blocking_ast()) ### MGC cancel CB ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x1009400000000 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927251:0:5365:0:(ldlm_request.c:1389:_ldlm_cancel_pack()) 1 locks packed
10000000:01000000:1.0:1715205923.927251:0:16624:0:(mgc_request.c:928:mgc_blocking_ast()) Lock res [0x65727473756c:0x2:0x0].0x0 (lustre)
10000000:01000000:1.0:1715205923.927252:0:16624:0:(mgc_request.c:940:mgc_blocking_ast()) log lustre-cliir: stopping, won't requeue
10000000:01000000:1.0:1715205923.927254:0:16624:0:(mgc_request.c:150:config_log_put()) dropping config log lustre-cliir
00010000:00010000:1.0:1715205923.927267:0:16624:0:(ldlm_request.c:1347:__ldlm_pack_lock()) ### packing ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 2/0,0 mode: --/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927269:0:5365:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 1/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927270:0:16624:0:(ldlm_request.c:1389:_ldlm_cancel_pack()) 1 locks packed
00010000:00010000:0.0:1715205923.927274:0:5365:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: MGC192.168.204.135@tcp lock: ffff8800aa964480/0x68b86d4dd46f4aec lrc: 0/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 4 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a977811173 expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927275:0:16624:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 1/0,0 mode: --/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927278:0:16624:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: MGC192.168.204.135@tcp lock: ffff8800aa974240/0x68b86d4dd46f4aad lrc: 0/0,0 mode: --/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 3 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a977810fc8 expref: -99 pid: 5353 timeout: 0 lvb_type: 0
00000020:00020000:2.0:1715205923.927278:0:21798:0:(lu_object.c:998:lu_site_print()) header@ffff8800b5ee60c8[0x4, 3, [0x200000402:0x3:0x0] hash]{
00010000:00010000:0.0:1715205923.927283:0:5365:0:(ldlm_lockd.c:2857:ldlm_bl_get_work()) Got 1 locks of 0 total in blp.  (0 blwis in pool)
00010000:00010000:0.0:1715205923.927289:0:5365:0:(ldlm_lockd.c:1947:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 2/0,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x1000400000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927293:0:16624:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:0.0:1715205923.927294:0:5365:0:(ldlm_lockd.c:1961:ldlm_handle_bl_callback()) Lock ffff8800aa95fcc0 already unused, calling callback (ffffffffa04cea40)
00010000:00010000:1.0:1715205923.927294:0:16624:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:1.0:1715205923.927295:0:16624:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
10000000:00010000:0.0:1715205923.927296:0:5365:0:(mgc_request.c:918:mgc_blocking_ast()) ### MGC blocking CB ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 2/0,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x1000400000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927301:0:5365:0:(ldlm_request.c:1300:ldlm_cli_cancel_local()) ### client-side cancel ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 3/0,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x1008400000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:1.0:1715205923.927303:0:5876:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
10000000:00010000:0.0:1715205923.927305:0:5365:0:(mgc_request.c:924:mgc_blocking_ast()) ### MGC cancel CB ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 3/0,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x1009400000000 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
10000000:01000000:0.0:1715205923.927309:0:5365:0:(mgc_request.c:928:mgc_blocking_ast()) Lock res [0x736d61726170:0x3:0x0].0x0 (params)
10000000:01000000:0.0:1715205923.927310:0:5365:0:(mgc_request.c:940:mgc_blocking_ast()) log params: stopping, won't requeue
10000000:01000000:0.0:1715205923.927312:0:5365:0:(mgc_request.c:150:config_log_put()) dropping config log params
00000100:00100000:1.0:1715205923.927316:0:2005:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa02ad80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2005:1798523513242944:192.168.204.135@tcp:103:ldlm_bl.0
00010000:00010000:0.0:1715205923.927325:0:5365:0:(ldlm_request.c:1347:__ldlm_pack_lock()) ### packing ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 2/0,0 mode: --/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927330:0:5365:0:(ldlm_request.c:1389:_ldlm_cancel_pack()) 1 locks packed
00010000:00010000:0.0:1715205923.927333:0:5365:0:(ldlm_lockd.c:1971:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 1/0,0 mode: --/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927338:0:5365:0:(ldlm_lock.c:215:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: MGC192.168.204.135@tcp lock: ffff8800aa95fcc0/0x68b86d4dd46f504f lrc: 0/0,0 mode: --/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 3 type: PLN flags: 0x5809400000020 nid: local remote: 0x4cb6a6a97781155c expref: -99 pid: 5367 timeout: 0 lvb_type: 0
00010000:00010000:0.0:1715205923.927345:0:5365:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:0.0:1715205923.927346:0:5365:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:0.0:1715205923.927348:0:5365:0:(ldlm_lockd.c:2860:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:0.0:1715205923.927357:0:2004:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa973100 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2004:1798523513242880:192.168.204.135@tcp:103:ldlm_bl.0
00000100:00100000:0.0:1715205923.927382:0:2004:0:(client.c:1776:ptlrpc_send_new_req()) Sending RPC req@ffff8800aa972d80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2004:1798523513243008:192.168.204.135@tcp:103:ldlm_bl.0
00000020:00020000:2.0:1715205923.929971:0:21798:0:(lu_object.c:998:lu_site_print()) ....lovsub@ffff8800b5ee6148[0]
00000100:00100000:0.0:1715205923.931300:0:2004:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa973100 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2004:1798523513242880:192.168.204.135@tcp:103:ldlm_bl.0
00000100:00100000:0.0:1715205923.931306:0:2004:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa972d80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2004:1798523513243008:192.168.204.135@tcp:103:ldlm_bl.0
00000100:00100000:1.0:1715205923.931332:0:2005:0:(client.c:2261:ptlrpc_check_set()) Completed RPC req@ffff8800aa02ad80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:13bd8ebe-30a8-40d5-b37d-8e600c74dbe8:2005:1798523513242944:192.168.204.135@tcp:103:ldlm_bl.0
00000020:00020000:2.0:1715205923.933283:0:21798:0:(lu_object.c:998:lu_site_print()) ....mdc@ffff8800a9db0190id: 0x200000402:3 idx: 0 gen: 0 kms_valid: 1 kms 7168 rc: 0 force_sync: 0 min_xid: 0 size: 7168 mtime: 1715205771 atime: 1715205771 ctime: 1715205771 blocks: 16
00000020:00020000:2.0:1715205923.941745:0:21798:0:(lu_object.c:998:lu_site_print()) } header@ffff8800b5ee60c8
00000020:00040000:2.0:1715205923.945227:0:21798:0:(lu_object.c:1250:lu_device_fini()) ASSERTION( atomic_read(&d->ld_ref) == 0 ) failed: Refcount is 1
00000020:00040000:2.0:1715205923.948770:0:21798:0:(lu_object.c:1250:lu_device_fini()) LBUG
Debug log: 203 lines, 203 kept, 0 dropped, 0 bad.