00000001:01000000:3.0F:1786414831.959240:0:2347:0:(lnet-crypto.c:359:cfs_crypto_performance_test()) Crypto hash algorithm adler32 speed = 626 MB/s
00000001:01000000:2.0F:1786414832.209409:0:2347:0:(lnet-crypto.c:359:cfs_crypto_performance_test()) Crypto hash algorithm crc32 speed = 500 MB/s
00000001:01000000:0.0F:1786414832.459546:0:2347:0:(lnet-crypto.c:359:cfs_crypto_performance_test()) Crypto hash algorithm crc32c speed = 4291 MB/s
00000020:02000000:1.0F:1786414833.329290:0:2376:0:(class_obd.c:804:obdclass_init()) Lustre: Build Version: 2.17.56_50_gb0a9653
00000400:02000000:1.0:1786414833.926060:0:2392:0:(api-ni.c:2822:lnet_startup_lndni()) Added LNI 192.168.204.44@tcp [8/256/0/180]
00008000:02000000:2.0:1786414837.818947:0:2542:0:(echo_client.c:2574:obdecho_init()) Echo OBD driver; http://www.lustre.org/
00000020:01000004:2.0:1786415080.811837:0:6181:0:(obd_mount.c:923:lmd_print())   mount data:
00000020:01000004:2.0:1786415080.811842:0:6181:0:(obd_mount.c:925:lmd_print()) profile: lustre-client
00000020:01000004:2.0:1786415080.811844:0:6181:0:(obd_mount.c:926:lmd_print()) device:  192.168.204.144@tcp:/lustre
00000020:01000004:2.0:1786415080.811845:0:6181:0:(obd_mount.c:929:lmd_print()) options: user_xattr,flock
00000080:01200004:2.0:1786415080.811983:0:6181:0:(super25.c:130:lustre_fill_super()) VFS Op: sb 0000000055f46929
00000080:01000004:2.0:1786415080.811993:0:6181:0:(super25.c:161:lustre_fill_super()) Mounting client lustre-client
00000020:01000004:2.0:1786415080.812009:0:6181:0:(obd_mount.c:411:lustre_start_mgc()) Start MGC 'MGC192.168.204.144@tcp'
00000020:01000004:2.0:1786415080.812058:0:6181:0:(obd_mount.c:230:lustre_start_simple()) Starting OBD MGC192.168.204.144@tcp (typ=mgc)
00000020:00000080:2.0:1786415080.812079:0:6181:0:(genops.c:413:class_newdev()) Allocate new device MGC192.168.204.144@tcp (000000004852ac47)
00000020:00000080:2.0:1786415080.812092:0:6181:0:(obd_config.c:720:class_attach_name()) OBD: dev 0 attached type mgc with refcount 2
00000100:00100000:2.0:1786415080.812309:0:6181:0:(service.c:155:ptlrpc_grow_req_bufs()) ldlm_cbd: allocate 1 new 8192-byte reqbufs (0/1 left), rc = 0
00000100:00100000:2.0:1786415080.812332:0:6181:0:(service.c:155:ptlrpc_grow_req_bufs()) ldlm_cbd: allocate 1 new 8192-byte reqbufs (0/1 left), rc = 0
00000100:00100000:2.0:1786415080.812448:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_cbd[0] started 0 min 2 max 34
00000100:00100000:2.0:1786415080.812454:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cb00_000'
00000100:00100000:2.0:1786415080.816959:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_cbd[0] started 1 min 2 max 34
00000100:00100000:2.0:1786415080.816975:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cb00_001'
00000100:00100000:2.0:1786415080.819407:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_cbd[1] started 0 min 2 max 34
00000100:00100000:2.0:1786415080.819496:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cb01_000'
00000100:00100000:1.0:1786415080.826416:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_cbd[1] started 1 min 2 max 34
00000100:00100000:1.0:1786415080.826429:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cb01_001'
00000100:00100000:1.0:1786415080.832285:0:6181:0:(service.c:155:ptlrpc_grow_req_bufs()) ldlm_canceld: allocate 64 new 8192-byte reqbufs (0/64 left), rc = 0
00000100:00100000:1.0:1786415080.832626:0:6181:0:(service.c:155:ptlrpc_grow_req_bufs()) ldlm_canceld: allocate 64 new 8192-byte reqbufs (0/64 left), rc = 0
00000100:00100000:1.0:1786415080.832816:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_canceld[0] started 0 min 3 max 34
00000100:00100000:1.0:1786415080.832824:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cn00_000'
00000100:00100000:2.0:1786415080.837884:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_canceld[0] started 1 min 3 max 34
00000100:00100000:2.0:1786415080.837902:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cn00_001'
00000100:00100000:2.0:1786415080.847024:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_canceld[0] started 2 min 3 max 34
00000100:00100000:2.0:1786415080.847041:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cn00_002'
00000100:00100000:1.0:1786415080.853003:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_canceld[1] started 0 min 3 max 34
00000100:00100000:1.0:1786415080.853022:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cn01_000'
00000100:00100000:1.0:1786415080.862816:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_canceld[1] started 1 min 3 max 34
00000100:00100000:1.0:1786415080.862836:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cn01_001'
00000100:00100000:3.0:1786415080.865978:0:6181:0:(service.c:3462:ptlrpc_start_thread()) ldlm_canceld[1] started 2 min 3 max 34
00000100:00100000:3.0:1786415080.865992:0:6181:0:(service.c:3521:ptlrpc_start_thread()) starting thread 'ldlm_cn01_002'
00010000:00010000:3.0:1786415080.868788:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415080.868794:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415080.868796:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415080.869418:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415080.869421:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415080.869423:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00080000:1.0:1786415080.871133:0:6181:0:(ldlm_lib.c:102:import_set_conn()) imp 000000001ea0e29b@MGC192.168.204.144@tcp: add connection 192.168.204.144@tcp at head
00010000:00010000:1.0:1786415080.871284:0:6181:0:(ldlm_pool.c:925:ldlm_pool_init()) Lock pool ldlm-pool-MGC192.168.204.144@tcp-0 is initialized
00000040:01000000:1.0:1786415080.871293:0:6181:0:(llog_obd.c:197:llog_setup()) obd MGC192.168.204.144@tcp ctxt 1 is initialized
10000000:01000000:1.0:1786415080.874870:0:6195:0:(mgc_request.c:631:mgc_requeue_thread()) Starting requeue thread
00000020:00000080:1.0:1786415080.874917:0:6181:0:(obd_config.c:826:class_setup()) finished setup of obd MGC192.168.204.144@tcp (uuid 596bd973-15df-48a1-9a23-d267a70f4eca)
00000020:00000080:1.0:1786415080.874938:0:6181:0:(genops.c:1406:class_connect()) connect: client 596bd973-15df-48a1-9a23-d267a70f4eca, cookie 0x85418d5ef8bb352b
00000100:00080000:1.0:1786415080.874954:0:6181:0:(import.c:510:import_select_connection()) MGC192.168.204.144@tcp: connect to NID 192.168.204.144@tcp last attempt 0
00000100:00080000:1.0:1786415080.874964:0:6181:0:(import.c:624:import_select_connection()) MGC192.168.204.144@tcp: import 000000001ea0e29b using connection 192.168.204.144@tcp/192.168.204.144@tcp
00000100:00100000:1.0:1786415080.875029:0:6181:0:(import.c:826:ptlrpc_connect_import_locked()) @@@ (re)connect request (timeout 5)  req@00000000cbfc2178 x1873191719927936/t0(0) o250->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 520/544 e 0 to 0 dl 0 ref 1 fl New:NQU/0/ffffffff rc 0/-1 job:'' uid:4294967295 gid:4294967295 projid:4294967295
00000100:00080000:1.0:1786415080.875047:0:6181:0:(pinger.c:373:ptlrpc_pinger_add_import()) adding pingable import 596bd973-15df-48a1-9a23-d267a70f4eca->MGS
00000080:00200000:1.0:1786415080.875069:0:6181:0:(llite_lib.c:1515:ll_fill_super()) VFS Op: cfg_instance lustre-client-ffff9a560b251800 (sb 0000000055f46929)
00000080:01000000:1.0:1786415080.875139:0:6181:0:(llite_lib.c:1241:ll_options()) Parsing opts user_xattr,flock
00000080:00000004:1.0:1786415080.875141:0:6181:0:(llite_lib.c:1251:ll_options()) next opt=user_xattr
00000080:00000004:1.0:1786415080.875145:0:6181:0:(llite_lib.c:1251:ll_options()) next opt=flock
00000080:01000000:1.0:1786415080.875152:0:6181:0:(llite_lib.c:1549:ll_fill_super()) llite sb uuid: 60eb3b9e-419f-472b-bd9c-bc0c604dab29
00010000:00010000:2.0:1786415080.875776:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:2.0:1786415080.875830:0:2408:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000cbfc2178 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:596bd973-15df-48a1-9a23-d267a70f4eca:2408:1873191719927936:192.168.204.144@tcp:250:
10000000:01000000:1.0:1786415080.876502:0:6181:0:(mgc_request.c:1943:mgc_process_config()) parse_log lustre-client from 0
10000000:01000000:1.0:1786415080.876505:0:6181:0:(mgc_request.c:329:config_log_add()) add config log lustre-client-ffff9a560b251800
10000000:01000000:1.0:1786415080.876509:0:6181:0:(mgc_request.c:194:do_config_log_add()) do adding config log lustre-sptlrpc-ffff9a56063a99c0
10000000:01000000:1.0:1786415080.876514:0:6181:0:(mgc_request.c:68:mgc_name2resid()) log lustre-sptlrpc to resid 0x65727473756c/0x0 (lustre)
10000000:01000000:1.0:1786415080.876526:0:6181:0:(mgc_request.c:1801:mgc_process_log()) Process log lustre-sptlrpc-ffff9a56063a99c0 from 1
10000000:01000000:1.0:1786415080.876529:0:6181:0:(mgc_request.c:996:mgc_enqueue()) Enqueue for lustre-sptlrpc (res 0x65727473756c)
00010000:00010000:1.0:1786415080.876672:0:6181:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x10000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.876694:0:6181:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000000000000 ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x0 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.876705:0:6181:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00080000:1.0:1786415080.876728:0:6181:0:(client.c:1832:ptlrpc_send_new_req()) @@@ req waiting for recovery: (FULL != CONNECTING)  req@00000000526d4621 x1873191719928064/t0(0) o101->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 328/344 e 0 to 0 dl 0 ref 2 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415080.876736:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000d176a50a going to sleep for 0 seconds
00000100:00100000:3.0:1786415080.887933:0:2408:0:(client.c:3112:ptlrpc_free_committed()) MGC192.168.204.144@tcp: committing for last_committed 0 gen 1
00000100:00080000:3.0:1786415080.887947:0:2408:0:(import.c:1132:ptlrpc_connect_interpret()) MGC192.168.204.144@tcp: connect to target with instance 0
00000100:00080000:3.0:1786415080.887955:0:2408:0:(import.c:956:ptlrpc_connect_set_flags()) MGC192.168.204.144@tcp: Resetting ns_connect_flags to server flags: 0xa000011001002060
10000000:01000000:3.0:1786415080.887962:0:2408:0:(mgc_request.c:1168:mgc_import_event()) import event 0x808005
00000100:00080000:3.0:1786415080.887965:0:2408:0:(import.c:65:import_set_state_nolock()) 000000001ea0e29b MGS: changing import state from CONNECTING to FULL
10000000:01000000:3.0:1786415080.887970:0:2408:0:(mgc_request.c:1168:mgc_import_event()) import event 0x808004
00000100:00080000:3.0:1786415080.887977:0:2408:0:(pinger.c:186:ptlrpc_pinger_ir_up()) IR up
00000100:00080000:3.0:1786415080.887989:0:2408:0:(recover.c:191:ptlrpc_wake_delayed()) @@@ waking (set 00000000d176a50a):  req@00000000526d4621 x1873191719928064/t0(0) o101->MGC192.168.204.144@tcp@192.168.204.144@tcp:26/25 lens 328/344 e 0 to 0 dl 0 ref 2 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:3.0:1786415080.892633:0:2408:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000cbfc2178 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:596bd973-15df-48a1-9a23-d267a70f4eca:2408:1873191719927936:192.168.204.144@tcp:250:
00000100:00100000:1.0:1786415080.895647:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000d176a50a going to sleep for 11 seconds
00000100:00100000:1.0:1786415080.903643:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928064:192.168.204.144@tcp:101:
00010000:00010000:1.0:1786415080.903739:0:6181:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.903752:0:6181:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:1.0:1786415080.903821:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928192:192.168.204.144@tcp:501:
00000100:00100000:1.0:1786415080.903899:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000d176a50a going to sleep for 11 seconds
00000100:00100000:1.0:1786415080.919792:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928192:192.168.204.144@tcp:501:
10000000:01000000:1.0:1786415080.919833:0:6181:0:(mgc_request.c:1878:mgc_process_log()) MGC192.168.204.144@tcp: configuration from log 'lustre-sptlrpc' failed (-2).
00010000:00010000:1.0:1786415080.919854:0:6181:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.919881:0:6181:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: MGC192.168.204.144@tcp lock: 0000000020dab501/0x85418d5ef8bb3532 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:01000000:1.0:1786415080.919890:0:6181:0:(mgc_request.c:194:do_config_log_add()) do adding config log params-ffff9a560b251800
10000000:01000000:1.0:1786415080.919895:0:6181:0:(mgc_request.c:68:mgc_name2resid()) log params to resid 0x736d61726170/0x3 (params)
10000000:01000000:1.0:1786415080.919899:0:6181:0:(mgc_request.c:194:do_config_log_add()) do adding config log lustre-client-ffff9a560b251800
10000000:01000000:1.0:1786415080.919901:0:6181:0:(mgc_request.c:68:mgc_name2resid()) log lustre-client to resid 0x65727473756c/0x0 (lustre)
10000000:01000000:1.0:1786415080.919904:0:6181:0:(mgc_request.c:194:do_config_log_add()) do adding config log lustre-cliir-ffff9a560b251800
10000000:01000000:1.0:1786415080.919907:0:6181:0:(mgc_request.c:68:mgc_name2resid()) log lustre-cliir to resid 0x65727473756c/0x2 (lustre)
10000000:01000000:1.0:1786415080.919910:0:6181:0:(mgc_request.c:1801:mgc_process_log()) Process log lustre-client-ffff9a560b251800 from 1
10000000:01000000:1.0:1786415080.919913:0:6181:0:(mgc_request.c:996:mgc_enqueue()) Enqueue for lustre-client (res 0x65727473756c)
00010000:00010000:1.0:1786415080.919943:0:6181:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x10000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.919950:0:6181:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000000000000 ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x0 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.919960:0:6181:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:1.0:1786415080.919979:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928320:192.168.204.144@tcp:101:
00000100:00100000:1.0:1786415080.920044:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000d176a50a going to sleep for 11 seconds
00000100:00100000:1.0:1786415080.927934:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928320:192.168.204.144@tcp:101:
00010000:00010000:1.0:1786415080.927965:0:6181:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415080.927977:0:6181:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1000000000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:1.0:1786415080.928071:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928448:192.168.204.144@tcp:501:
00000100:00100000:1.0:1786415080.928159:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000d176a50a going to sleep for 11 seconds
00000100:00100000:1.0:1786415080.941475:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928448:192.168.204.144@tcp:501:
00000100:00100000:1.0:1786415080.941556:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928576:192.168.204.144@tcp:503:
00000100:00100000:1.0:1786415080.943300:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000d176a50a going to sleep for 11 seconds
00000100:00100000:3.0:1786415080.949426:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000526d4621 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928576:192.168.204.144@tcp:503:
00000100:00100000:0.0:1786415080.950810:0:6200:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@000000009032bb8b pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6200:1873191719928704:192.168.204.144@tcp:502:
00000100:00100000:0.0:1786415080.951125:0:6200:0:(client.c:2702:ptlrpc_set_wait()) set 00000000eee8b64b going to sleep for 11 seconds
00000100:00100000:0.0:1786415080.959396:0:6200:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@000000009032bb8b pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6200:1873191719928704:192.168.204.144@tcp:502:
00000020:01000000:0.0:1786415080.959444:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:0.0:1786415080.959458:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.959460:0:6200:0:(obd_config.c:1561:class_process_config()) marker 4 (0x1) lustre-clilov lov setup
00000020:01000000:0.0:1786415080.959466:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf001, instance name: lustre-clilov-ffff9a560b251800
00000020:00000080:0.0:1786415080.959469:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf001
00000020:00000080:0.0:1786415080.959498:0:6200:0:(genops.c:413:class_newdev()) Allocate new device lustre-clilov-ffff9a560b251800 (00000000bf955858)
00000020:00000080:0.0:1786415080.959518:0:6200:0:(obd_config.c:720:class_attach_name()) OBD: dev 1 attached type lov with refcount 2
00000020:01000000:0.0:1786415080.959526:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf003, instance name: lustre-clilov-ffff9a560b251800
00000020:00000080:0.0:1786415080.959528:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf003
00000020:00000080:0.0:1786415080.964270:0:6200:0:(obd_config.c:826:class_setup()) finished setup of obd lustre-clilov-ffff9a560b251800 (uuid 60eb3b9e-419f-472b-bd9c-bc0c604dab29)
00000020:01000000:0.0:1786415080.964284:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:0.0:1786415080.964289:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.964291:0:6200:0:(obd_config.c:1561:class_process_config()) marker 4 (0x2) lustre-clilov lov setup
00000020:01000000:0.0:1786415080.964294:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:0.0:1786415080.964298:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.964299:0:6200:0:(obd_config.c:1561:class_process_config()) marker 5 (0x1) lustre-clilmv lmv setup
00000020:01000000:0.0:1786415080.964303:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf001, instance name: lustre-clilmv-ffff9a560b251800
00000020:00000080:0.0:1786415080.964306:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf001
00000020:00000080:0.0:1786415080.964314:0:6200:0:(genops.c:413:class_newdev()) Allocate new device lustre-clilmv-ffff9a560b251800 (000000001012da97)
00000020:00000080:0.0:1786415080.964330:0:6200:0:(obd_config.c:720:class_attach_name()) OBD: dev 2 attached type lmv with refcount 2
00000020:01000000:0.0:1786415080.964334:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf003, instance name: lustre-clilmv-ffff9a560b251800
00000020:00000080:0.0:1786415080.964337:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf003
00000020:00000080:0.0:1786415080.970876:0:6200:0:(obd_config.c:826:class_setup()) finished setup of obd lustre-clilmv-ffff9a560b251800 (uuid 60eb3b9e-419f-472b-bd9c-bc0c604dab29)
00000020:01000000:0.0:1786415080.970890:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:0.0:1786415080.970897:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.970900:0:6200:0:(obd_config.c:1561:class_process_config()) marker 5 (0x2) lustre-clilmv lmv setup
00000020:01000000:0.0:1786415080.970903:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:0.0:1786415080.970913:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.970914:0:6200:0:(obd_config.c:1561:class_process_config()) marker 6 (0x1) lustre-client mount opts
00000020:00000080:0.0:1786415080.970918:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf007
00000020:00000080:0.0:1786415080.970920:0:6200:0:(obd_config.c:1514:class_process_config()) mountopt: profile lustre-client osc lustre-clilov mdc lustre-clilmv
00000020:01000000:0.0:1786415080.970922:0:6200:0:(obd_config.c:1148:class_add_profile()) Add profile lustre-client
00000020:01000000:0.0:1786415080.970928:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:0.0:1786415080.970929:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.970931:0:6200:0:(obd_config.c:1561:class_process_config()) marker 6 (0x2) lustre-client mount opts
00000020:01000000:0.0:1786415080.970932:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:0.0:1786415080.970936:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.970937:0:6200:0:(obd_config.c:1561:class_process_config()) marker 7 (0x1) lustre-MDT0000 add mdc
00000020:00000080:0.0:1786415080.970940:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf005
00000020:00000080:0.0:1786415080.970945:0:6200:0:(obd_config.c:1485:class_process_config()) adding mapping from uuid 192.168.204.144@tcp to nid 0x20000c0a8cc90 (192.168.204.144@tcp)
00000020:01000000:0.0:1786415080.970972:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf001, instance name: lustre-MDT0000-mdc-ffff9a560b251800
00000020:00000080:0.0:1786415080.970975:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf001
00000020:00000080:0.0:1786415080.970985:0:6200:0:(genops.c:413:class_newdev()) Allocate new device lustre-MDT0000-mdc-ffff9a560b251800 (0000000007aae90a)
00000020:00000080:0.0:1786415080.971002:0:6200:0:(obd_config.c:720:class_attach_name()) OBD: dev 3 attached type mdc with refcount 2
00000020:01000000:0.0:1786415080.971008:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf003, instance name: lustre-MDT0000-mdc-ffff9a560b251800
00000020:00000080:0.0:1786415080.971010:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf003
00010000:00080000:0.0:1786415080.971089:0:6200:0:(ldlm_lib.c:102:import_set_conn()) imp 0000000061284331@lustre-MDT0000-mdc-ffff9a560b251800: add connection 192.168.204.144@tcp at head
00010000:00010000:0.0:1786415080.983970:0:6200:0:(ldlm_pool.c:925:ldlm_pool_init()) Lock pool ldlm-pool-lustre-MDT0000-mdc-ffff9a560b251800-1 is initialized
00000040:01000000:0.0:1786415080.984632:0:6200:0:(llog_obd.c:197:llog_setup()) obd lustre-MDT0000-mdc-ffff9a560b251800 ctxt 13 is initialized
00000020:00000080:0.0:1786415080.991773:0:6200:0:(obd_config.c:826:class_setup()) finished setup of obd lustre-MDT0000-mdc-ffff9a560b251800 (uuid 60eb3b9e-419f-472b-bd9c-bc0c604dab29)
00000020:01000000:0.0:1786415080.991792:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf014, instance name: lustre-clilmv-ffff9a560b251800
00000020:00000080:0.0:1786415080.991796:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf014
00800000:01000000:0.0:1786415080.992114:0:6200:0:(lmv_obd.c:442:lmv_add_target()) Target uuid: lustre-MDT0000_UUID. index 0
00000020:01000000:0.0:1786415080.992154:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:0.0:1786415080.992158:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.992159:0:6200:0:(obd_config.c:1561:class_process_config()) marker 7 (0x2) lustre-MDT0000 add mdc
00000020:01000000:0.0:1786415080.992162:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x5
00000020:01000000:0.0:1786415080.992165:0:6200:0:(obd_config.c:1899:class_config_llog_handler()) SKIP #9
00000020:01000000:0.0:1786415080.992166:0:6200:0:(obd_config.c:1925:class_config_llog_handler()) skipping 0x6
00000020:01000000:0.0:1786415080.992172:0:6200:0:(obd_config.c:1925:class_config_llog_handler()) skipping 0x6
00000020:01000000:0.0:1786415080.992174:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x6 mark_flg=0x6
00000020:00000080:0.0:1786415080.992176:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.992177:0:6200:0:(obd_config.c:1561:class_process_config()) marker 9 (0x6) lustre sys.timeout
00000020:01000000:0.0:1786415080.992240:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:0.0:1786415080.992244:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415080.992245:0:6200:0:(obd_config.c:1561:class_process_config()) marker 16 (0x1) lustre-MDT0001 add mdc
00000020:00000080:0.0:1786415080.992249:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf005
00000020:00000080:0.0:1786415080.992254:0:6200:0:(obd_config.c:1485:class_process_config()) adding mapping from uuid 192.168.204.144@tcp to nid 0x20000c0a8cc90 (192.168.204.144@tcp)
00000020:01000000:0.0:1786415080.992278:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf001, instance name: lustre-MDT0001-mdc-ffff9a560b251800
00000020:00000080:0.0:1786415080.992281:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf001
00000020:00000080:0.0:1786415080.992291:0:6200:0:(genops.c:413:class_newdev()) Allocate new device lustre-MDT0001-mdc-ffff9a560b251800 (00000000c1587fc3)
00000020:00000080:0.0:1786415080.992306:0:6200:0:(obd_config.c:720:class_attach_name()) OBD: dev 4 attached type mdc with refcount 2
00000020:01000000:0.0:1786415080.992310:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf003, instance name: lustre-MDT0001-mdc-ffff9a560b251800
00000020:00000080:0.0:1786415080.992313:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf003
00010000:00080000:0.0:1786415080.992377:0:6200:0:(ldlm_lib.c:102:import_set_conn()) imp 00000000b6777ec4@lustre-MDT0001-mdc-ffff9a560b251800: add connection 192.168.204.144@tcp at head
00010000:00010000:0.0:1786415081.003822:0:6200:0:(ldlm_pool.c:925:ldlm_pool_init()) Lock pool ldlm-pool-lustre-MDT0001-mdc-ffff9a560b251800-2 is initialized
00000040:01000000:0.0:1786415081.009652:0:6200:0:(llog_obd.c:197:llog_setup()) obd lustre-MDT0001-mdc-ffff9a560b251800 ctxt 13 is initialized
00000020:00000080:0.0:1786415081.010007:0:6200:0:(obd_config.c:826:class_setup()) finished setup of obd lustre-MDT0001-mdc-ffff9a560b251800 (uuid 60eb3b9e-419f-472b-bd9c-bc0c604dab29)
00000020:01000000:0.0:1786415081.010022:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf014, instance name: lustre-clilmv-ffff9a560b251800
00000020:00000080:0.0:1786415081.010025:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf014
00800000:01000000:0.0:1786415081.010046:0:6200:0:(lmv_obd.c:442:lmv_add_target()) Target uuid: lustre-MDT0001_UUID. index 1
00000020:01000000:0.0:1786415081.010077:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:0.0:1786415081.010081:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415081.010082:0:6200:0:(obd_config.c:1561:class_process_config()) marker 16 (0x2) lustre-MDT0001 add mdc
00000020:01000000:0.0:1786415081.010085:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x5
00000020:01000000:0.0:1786415081.010087:0:6200:0:(obd_config.c:1899:class_config_llog_handler()) SKIP #20
00000020:01000000:0.0:1786415081.010089:0:6200:0:(obd_config.c:1925:class_config_llog_handler()) skipping 0x6
00000020:01000000:0.0:1786415081.010090:0:6200:0:(obd_config.c:1925:class_config_llog_handler()) skipping 0x6
00000020:01000000:0.0:1786415081.010091:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x6 mark_flg=0x6
00000020:00000080:0.0:1786415081.010093:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415081.010094:0:6200:0:(obd_config.c:1561:class_process_config()) marker 20 (0x6) lustre sys.timeout
00000020:01000000:0.0:1786415081.010107:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:01000004:0.0:1786415081.010117:0:6200:0:(obd_mount.c:961:lustre_check_exclusion()) Check exclusion lustre-OST0000 (0) in 0 of 192.168.204.144@tcp:/lustre
00000020:00000080:0.0:1786415081.010121:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:0.0:1786415081.010122:0:6200:0:(obd_config.c:1561:class_process_config()) marker 24 (0x1) lustre-OST0000 add osc
00000020:00000080:0.0:1786415081.010125:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf005
00000020:00000080:0.0:1786415081.010130:0:6200:0:(obd_config.c:1485:class_process_config()) adding mapping from uuid 192.168.204.144@tcp to nid 0x20000c0a8cc90 (192.168.204.144@tcp)
00000020:01000000:0.0:1786415081.010168:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf001, instance name: lustre-OST0000-osc-ffff9a560b251800
00000020:00000080:0.0:1786415081.010172:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf001
00000020:00000080:0.0:1786415081.010190:0:6200:0:(genops.c:413:class_newdev()) Allocate new device lustre-OST0000-osc-ffff9a560b251800 (00000000cc186291)
00000020:00000080:0.0:1786415081.010204:0:6200:0:(obd_config.c:720:class_attach_name()) OBD: dev 5 attached type osc with refcount 2
00000020:01000000:0.0:1786415081.010211:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf003, instance name: lustre-OST0000-osc-ffff9a560b251800
00000020:00000080:0.0:1786415081.010213:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf003
00010000:00080000:0.0:1786415081.010279:0:6200:0:(ldlm_lib.c:102:import_set_conn()) imp 000000000cb56a22@lustre-OST0000-osc-ffff9a560b251800: add connection 192.168.204.144@tcp at head
00010000:00010000:0.0:1786415081.012795:0:6200:0:(ldlm_pool.c:925:ldlm_pool_init()) Lock pool ldlm-pool-lustre-OST0000-osc-ffff9a560b251800-3 is initialized
00000020:00000080:0.0:1786415081.021797:0:6200:0:(obd_config.c:826:class_setup()) finished setup of obd lustre-OST0000-osc-ffff9a560b251800 (uuid 60eb3b9e-419f-472b-bd9c-bc0c604dab29)
00000020:01000000:1.0:1786415081.028748:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf00d, instance name: lustre-clilov-ffff9a560b251800
00000020:00000080:1.0:1786415081.028757:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf00d
00020000:01000000:1.0:1786415081.028786:0:6200:0:(lov_obd.c:481:lov_add_target()) uuid:lustre-OST0000_UUID idx:0 gen:1 active:1
00020000:01000000:1.0:1786415081.028809:0:6200:0:(lov_obd.c:510:lov_add_target()) lustre-clilov-ffff9a560b251800: Adding target lustre-OST0000_UUID
00020000:01000000:1.0:1786415081.028816:0:6200:0:(lov_obd.c:532:lov_add_target()) idx=0 ltd_gen=1 ld_tgt_count=1
00000020:01000000:1.0:1786415081.028840:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:1.0:1786415081.028846:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.028848:0:6200:0:(obd_config.c:1561:class_process_config()) marker 24 (0x2) lustre-OST0000 add osc
00000020:01000000:1.0:1786415081.028852:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x5
00000020:01000000:1.0:1786415081.028854:0:6200:0:(obd_config.c:1899:class_config_llog_handler()) SKIP #28
00000020:01000000:1.0:1786415081.028855:0:6200:0:(obd_config.c:1925:class_config_llog_handler()) skipping 0x6
00000020:01000000:1.0:1786415081.028857:0:6200:0:(obd_config.c:1925:class_config_llog_handler()) skipping 0x6
00000020:01000000:1.0:1786415081.028858:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x6 mark_flg=0x6
00000020:00000080:1.0:1786415081.028861:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.028862:0:6200:0:(obd_config.c:1561:class_process_config()) marker 28 (0x6) lustre sys.timeout
00000020:01000000:1.0:1786415081.028864:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:01000004:1.0:1786415081.028868:0:6200:0:(obd_mount.c:961:lustre_check_exclusion()) Check exclusion lustre-OST0001 (1) in 0 of 192.168.204.144@tcp:/lustre
00000020:00000080:1.0:1786415081.028871:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.028873:0:6200:0:(obd_config.c:1561:class_process_config()) marker 32 (0x1) lustre-OST0001 add osc
00000020:00000080:1.0:1786415081.028876:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf005
00000020:00000080:1.0:1786415081.028881:0:6200:0:(obd_config.c:1485:class_process_config()) adding mapping from uuid 192.168.204.144@tcp to nid 0x20000c0a8cc90 (192.168.204.144@tcp)
00000020:01000000:1.0:1786415081.028908:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf001, instance name: lustre-OST0001-osc-ffff9a560b251800
00000020:00000080:1.0:1786415081.028911:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf001
00000020:00000080:1.0:1786415081.028933:0:6200:0:(genops.c:413:class_newdev()) Allocate new device lustre-OST0001-osc-ffff9a560b251800 (00000000fdc42df0)
00000020:00000080:1.0:1786415081.028951:0:6200:0:(obd_config.c:720:class_attach_name()) OBD: dev 6 attached type osc with refcount 2
00000020:01000000:1.0:1786415081.028958:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf003, instance name: lustre-OST0001-osc-ffff9a560b251800
00000020:00000080:1.0:1786415081.028960:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf003
00010000:00080000:1.0:1786415081.029068:0:6200:0:(ldlm_lib.c:102:import_set_conn()) imp 0000000042bab258@lustre-OST0001-osc-ffff9a560b251800: add connection 192.168.204.144@tcp at head
00010000:00010000:1.0:1786415081.029290:0:6200:0:(ldlm_pool.c:925:ldlm_pool_init()) Lock pool ldlm-pool-lustre-OST0001-osc-ffff9a560b251800-4 is initialized
00000020:00000080:1.0:1786415081.034132:0:6200:0:(obd_config.c:826:class_setup()) finished setup of obd lustre-OST0001-osc-ffff9a560b251800 (uuid 60eb3b9e-419f-472b-bd9c-bc0c604dab29)
00000020:01000000:1.0:1786415081.034150:0:6200:0:(obd_config.c:2005:class_config_llog_handler()) cmd cf00d, instance name: lustre-clilov-ffff9a560b251800
00000020:00000080:1.0:1786415081.034154:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf00d
00020000:01000000:1.0:1786415081.034177:0:6200:0:(lov_obd.c:481:lov_add_target()) uuid:lustre-OST0001_UUID idx:1 gen:1 active:1
00020000:01000000:1.0:1786415081.034184:0:6200:0:(lov_obd.c:510:lov_add_target()) lustre-clilov-ffff9a560b251800: Adding target lustre-OST0001_UUID
00020000:01000000:1.0:1786415081.034188:0:6200:0:(lov_obd.c:532:lov_add_target()) idx=1 ltd_gen=1 ld_tgt_count=2
00000020:01000000:1.0:1786415081.034199:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:1.0:1786415081.034203:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.034204:0:6200:0:(obd_config.c:1561:class_process_config()) marker 32 (0x2) lustre-OST0001 add osc
00000020:01000000:1.0:1786415081.034207:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:1.0:1786415081.034211:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.034212:0:6200:0:(obd_config.c:1561:class_process_config()) marker 37 (0x1) lustre sys.timeout
00000020:00000080:1.0:1786415081.034214:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf009
00000020:00000080:1.0:1786415081.034216:0:6200:0:(obd_config.c:1535:class_process_config()) changing lustre timeout from 100 to 20
00000100:00100000:1.0:1786415081.034262:0:6200:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@0000000097b84c92 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6200:1873191719928832:192.168.204.144@tcp:502:
00000100:00100000:1.0:1786415081.034661:0:6200:0:(client.c:2702:ptlrpc_set_wait()) set 0000000049bdb973 going to sleep for 11 seconds
00000100:00100000:3.0:1786415081.049858:0:6200:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@0000000097b84c92 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6200:1873191719928832:192.168.204.144@tcp:502:
00000020:01000000:3.0:1786415081.049902:0:6200:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:3.0:1786415081.049912:0:6200:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:3.0:1786415081.049914:0:6200:0:(obd_config.c:1561:class_process_config()) marker 37 (0x2) lustre sys.timeout
00000040:00080000:3.0:1786415081.049918:0:6200:0:(llog.c:862:llog_process_thread()) stop processing plain [0xa:0x3:0x0] index 48 count 48
00000020:01000000:1.0:1786415081.050824:0:6181:0:(obd_config.c:2145:class_config_parse_llog()) Processed log lustre-client gen 1-47 (rc=0)
10000000:01000000:1.0:1786415081.050848:0:6181:0:(mgc_request.c:1878:mgc_process_log()) MGC192.168.204.144@tcp: configuration from log 'lustre-client' succeeded (0).
00010000:00010000:1.0:1786415081.050918:0:6181:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415081.050929:0:6181:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: MGC192.168.204.144@tcp lock: 00000000c8399df9/0x85418d5ef8bb3539 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:01000000:1.0:1786415081.050936:0:6181:0:(mgc_request.c:1801:mgc_process_log()) Process log lustre-cliir-ffff9a560b251800 from 1
10000000:01000000:1.0:1786415081.050951:0:6181:0:(mgc_request.c:996:mgc_enqueue()) Enqueue for lustre-cliir (res 0x65727473756c)
00010000:00010000:1.0:1786415081.050985:0:6181:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x10000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415081.050992:0:6181:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000000000000 ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x0 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415081.051000:0:6181:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:1.0:1786415081.051019:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000a73dfd98 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928960:192.168.204.144@tcp:101:
00000100:00100000:1.0:1786415081.051130:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 000000006262f3e6 going to sleep for 11 seconds
00000100:00100000:3.0:1786415081.053762:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000a73dfd98 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719928960:192.168.204.144@tcp:101:
00010000:00010000:3.0:1786415081.053789:0:6181:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85846 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415081.053816:0:6181:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x5eec1eb7faf85846 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:3.0:1786415081.054406:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@000000001c1f25c8 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929088:192.168.204.144@tcp:256:
00000100:00100000:3.0:1786415081.054484:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000bcc815a5 going to sleep for 11 seconds
00000100:00100000:3.0:1786415081.061561:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@000000001c1f25c8 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929088:192.168.204.144@tcp:256:
10000000:01000000:3.0:1786415081.062919:0:6181:0:(mgc_request.c:1878:mgc_process_log()) MGC192.168.204.144@tcp: configuration from log 'lustre-cliir' succeeded (0).
00010000:00010000:3.0:1786415081.062937:0:6181:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85846 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415081.062947:0:6181:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: MGC192.168.204.144@tcp lock: 000000009a4c5544/0x85418d5ef8bb356a lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x2:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85846 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:01000000:2.0:1786415081.063211:0:6181:0:(mgc_request.c:1801:mgc_process_log()) Process log params-ffff9a560b251800 from 1
10000000:01000000:2.0:1786415081.063215:0:6181:0:(mgc_request.c:996:mgc_enqueue()) Enqueue for params (res 0x736d61726170)
00010000:00010000:2.0:1786415081.063273:0:6181:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.144@tcp lock: 000000008918701e/0x85418d5ef8bb3571 lrc: 3/1,0 mode: --/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x10000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:2.0:1786415081.063281:0:6181:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000000000000 ns: MGC192.168.204.144@tcp lock: 000000008918701e/0x85418d5ef8bb3571 lrc: 3/1,0 mode: --/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x0 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:2.0:1786415081.063289:0:6181:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: MGC192.168.204.144@tcp lock: 000000008918701e/0x85418d5ef8bb3571 lrc: 3/1,0 mode: --/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x0 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:2.0:1786415081.063316:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@000000008459ca62 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929216:192.168.204.144@tcp:101:
00000100:00100000:2.0:1786415081.063444:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 0000000077390484 going to sleep for 11 seconds
00000100:00100000:1.0:1786415081.067824:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@000000008459ca62 pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929216:192.168.204.144@tcp:101:
00010000:00010000:1.0:1786415081.067852:0:6181:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: MGC192.168.204.144@tcp lock: 000000008918701e/0x85418d5ef8bb3571 lrc: 4/1,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8584d expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415081.067862:0:6181:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: MGC192.168.204.144@tcp lock: 000000008918701e/0x85418d5ef8bb3571 lrc: 4/1,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x5eec1eb7faf8584d expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:1.0:1786415081.067936:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@000000005690dcdb pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929344:192.168.204.144@tcp:501:
00000100:00100000:1.0:1786415081.068015:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000dec549c3 going to sleep for 11 seconds
00000100:00100000:1.0:1786415081.074956:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@000000005690dcdb pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929344:192.168.204.144@tcp:501:
00000100:00100000:1.0:1786415081.075017:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@000000005690dcdb pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929472:192.168.204.144@tcp:503:
00000100:00100000:1.0:1786415081.075159:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set 00000000dec549c3 going to sleep for 11 seconds
00000100:00100000:1.0:1786415081.079848:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@000000005690dcdb pname:cluuid:pid:xid:nid:opc:job mount.lustre:596bd973-15df-48a1-9a23-d267a70f4eca:6181:1873191719929472:192.168.204.144@tcp:503:
00000100:00100000:2.0:1786415081.080279:0:6206:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@00000000f94a76f6 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6206:1873191719929600:192.168.204.144@tcp:502:
00000100:00100000:2.0:1786415081.080417:0:6206:0:(client.c:2702:ptlrpc_set_wait()) set 0000000077390484 going to sleep for 11 seconds
00000100:00100000:2.0:1786415081.086886:0:6206:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@00000000f94a76f6 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6206:1873191719929600:192.168.204.144@tcp:502:
00000020:01000000:1.0:1786415081.087027:0:6206:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:1.0:1786415081.087048:0:6206:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.087051:0:6206:0:(obd_config.c:1561:class_process_config()) marker 2 (0x1) general debug_raw_pointers
00000020:01000000:1.0:1786415081.087058:0:6206:0:(obd_config.c:2005:class_config_llog_handler()) cmd ce032, instance name: general-ffff9a560b251800
00000020:00000080:1.0:1786415081.087062:0:6206:0:(obd_config.c:1470:class_process_config()) processing cmd: ce032
00000100:00100000:1.0:1786415081.087128:0:6206:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e39f80 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6206:1873191719929728:192.168.204.144@tcp:502:
00000100:00100000:1.0:1786415081.087216:0:6206:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5609cab9c0 going to sleep for 11 seconds
00000100:00100000:1.0:1786415081.091464:0:6206:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e39f80 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6206:1873191719929728:192.168.204.144@tcp:502:
00000020:01000000:1.0:1786415081.091510:0:6206:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:1.0:1786415081.091518:0:6206:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415081.091521:0:6206:0:(obd_config.c:1561:class_process_config()) marker 2 (0x2) general debug_raw_pointers
00000040:00080000:1.0:1786415081.091526:0:6206:0:(llog.c:862:llog_process_thread()) stop processing plain [0xa:0x2:0x0] index 4 count 4
00000020:01000000:2.0:1786415081.092733:0:6181:0:(obd_config.c:2145:class_config_parse_llog()) Processed log params gen 1-3 (rc=0)
10000000:01000000:2.0:1786415081.092757:0:6181:0:(mgc_request.c:1878:mgc_process_log()) MGC192.168.204.144@tcp: configuration from log 'params' succeeded (0).
00010000:00010000:2.0:1786415081.092823:0:6181:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.144@tcp lock: ffff9a5646353e00/0x85418d5ef8bb3571 lrc: 3/1,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8584d expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:2.0:1786415081.092837:0:6181:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: MGC192.168.204.144@tcp lock: ffff9a5646353e00/0x85418d5ef8bb3571 lrc: 2/0,0 mode: CR/CR res: [0x736d61726170:0x3:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8584d expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000080:01000000:2.0:1786415081.092858:0:6181:0:(llite_lib.c:1623:ll_fill_super()) Found profile lustre-client: mdc=lustre-clilmv osc=lustre-clilov
00000020:00000080:2.0:1786415081.092883:0:6181:0:(genops.c:1406:class_connect()) connect: client 60eb3b9e-419f-472b-bd9c-bc0c604dab29, cookie 0x85418d5ef8bb3578
00800000:01000000:2.0:1786415081.092908:0:6181:0:(lmv_obd.c:515:lmv_check_connect()) Time to connect 60eb3b9e-419f-472b-bd9c-bc0c604dab29 to lustre-clilmv-ffff9a560b251800
00800000:01000000:2.0:1786415081.092915:0:6181:0:(lmv_obd.c:338:lmv_connect_mdc()) connect to lustre-MDT0000-mdc-ffff9a560b251800(60eb3b9e-419f-472b-bd9c-bc0c604dab29) - lustre-MDT0000_UUID, 60eb3b9e-419f-472b-bd9c-bc0c604dab29
00000020:00000080:2.0:1786415081.092922:0:6181:0:(genops.c:1406:class_connect()) connect: client 60eb3b9e-419f-472b-bd9c-bc0c604dab29, cookie 0x85418d5ef8bb357f
00000100:00080000:2.0:1786415081.092932:0:6181:0:(import.c:510:import_select_connection()) lustre-MDT0000-mdc-ffff9a560b251800: connect to NID 192.168.204.144@tcp last attempt 0
00000100:00080000:2.0:1786415081.092941:0:6181:0:(import.c:624:import_select_connection()) lustre-MDT0000-mdc-ffff9a560b251800: import ffff9a560bf38800 using connection 192.168.204.144@tcp/192.168.204.144@tcp
00000008:00100000:2.0:1786415081.092952:0:6181:0:(osc_request.c:3829:osc_reconnect()) ocd_connect_flags: 0xae7e7e7be374d3b8 ocd_version: 34682930 ocd_grant: 134217728, lost: 0.
00000100:00100000:2.0:1786415081.092978:0:6181:0:(import.c:826:ptlrpc_connect_import_locked()) @@@ (re)connect request (timeout 5)  req@ffff9a5609b23480 x1873191719929856/t0(0) o38->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 520/544 e 0 to 0 dl 0 ref 1 fl New:NQU/0/ffffffff rc 0/-1 job:'' uid:4294967295 gid:4294967295 projid:4294967295
00000100:00080000:2.0:1786415081.093023:0:6181:0:(pinger.c:373:ptlrpc_pinger_add_import()) adding pingable import 60eb3b9e-419f-472b-bd9c-bc0c604dab29->lustre-MDT0000_UUID
00800000:01000000:2.0:1786415081.093149:0:6181:0:(lmv_obd.c:392:lmv_connect_mdc()) Connected to lustre-MDT0000-mdc-ffff9a560b251800(60eb3b9e-419f-472b-bd9c-bc0c604dab29) successfully (5)
00800000:00000004:2.0:1786415081.093153:0:6181:0:(obd_class.h:1057:obd_statfs_async()) lustre-MDT0000-mdc-ffff9a560b251800: age -618, max_age 0
00800000:01000000:2.0:1786415081.093182:0:6181:0:(lmv_obd.c:338:lmv_connect_mdc()) connect to lustre-MDT0001-mdc-ffff9a560b251800(60eb3b9e-419f-472b-bd9c-bc0c604dab29) - lustre-MDT0001_UUID, 60eb3b9e-419f-472b-bd9c-bc0c604dab29
00000020:00000080:2.0:1786415081.093190:0:6181:0:(genops.c:1406:class_connect()) connect: client 60eb3b9e-419f-472b-bd9c-bc0c604dab29, cookie 0x85418d5ef8bb3586
00000100:00080000:2.0:1786415081.093195:0:6181:0:(import.c:510:import_select_connection()) lustre-MDT0001-mdc-ffff9a560b251800: connect to NID 192.168.204.144@tcp last attempt 0
00000100:00080000:2.0:1786415081.093200:0:6181:0:(import.c:624:import_select_connection()) lustre-MDT0001-mdc-ffff9a560b251800: import ffff9a560bf3e800 using connection 192.168.204.144@tcp/192.168.204.144@tcp
00000008:00100000:2.0:1786415081.093205:0:6181:0:(osc_request.c:3829:osc_reconnect()) ocd_connect_flags: 0xae7e7e7be374d3b8 ocd_version: 34682930 ocd_grant: 134217728, lost: 0.
00000100:00100000:2.0:1786415081.093215:0:6181:0:(import.c:826:ptlrpc_connect_import_locked()) @@@ (re)connect request (timeout 5)  req@ffff9a5609b23800 x1873191719930112/t0(0) o38->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 520/544 e 0 to 0 dl 0 ref 1 fl New:NQU/0/ffffffff rc 0/-1 job:'' uid:4294967295 gid:4294967295 projid:4294967295
00000100:00080000:2.0:1786415081.093223:0:6181:0:(pinger.c:373:ptlrpc_pinger_add_import()) adding pingable import 60eb3b9e-419f-472b-bd9c-bc0c604dab29->lustre-MDT0001_UUID
00000100:00100000:3.0:1786415081.095215:0:2408:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b23480 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719929856:192.168.204.144@tcp:38:
00000100:00100000:3.0:1786415081.095326:0:2408:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b23800 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719930112:192.168.204.144@tcp:38:
00000100:00080000:3.0:1786415081.095456:0:2412:0:(client.c:1832:ptlrpc_send_new_req()) @@@ req waiting for recovery: (FULL != CONNECTING)  req@ffff9a5609b22d80 x1873191719929984/t0(0) o41->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 0 ref 1 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00800000:01000000:2.0:1786415081.096296:0:6181:0:(lmv_obd.c:392:lmv_connect_mdc()) Connected to lustre-MDT0001-mdc-ffff9a560b251800(60eb3b9e-419f-472b-bd9c-bc0c604dab29) successfully (5)
00800000:00000004:2.0:1786415081.096302:0:6181:0:(obd_class.h:1057:obd_statfs_async()) lustre-MDT0001-mdc-ffff9a560b251800: age -618, max_age 0
00000100:00100000:2.0:1786415081.096354:0:2412:0:(ptlrpcd.c:344:ptlrpcd_check()) transfer 1 async RPCs [0->1]
00000100:00080000:2.0:1786415081.096364:0:2412:0:(client.c:1832:ptlrpc_send_new_req()) @@@ req waiting for recovery: (FULL != CONNECTING)  req@ffff9a5609b20380 x1873191719930240/t0(0) o41->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 0 ref 1 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000080:00000004:2.0:1786415081.096407:0:6181:0:(obd_class.h:1091:obd_statfs()) lustre-clilmv-ffff9a560b251800: age -618, max_age 381
00800000:00000004:2.0:1786415081.096413:0:6181:0:(obd_class.h:1091:obd_statfs()) lustre-MDT0000-mdc-ffff9a560b251800: age -618, max_age 381
00000100:00080000:2.0:1786415081.096431:0:6181:0:(client.c:1832:ptlrpc_send_new_req()) @@@ req waiting for recovery: (FULL != CONNECTING)  req@ffff9a5609b23b80 x1873191719930368/t0(0) o41->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 0 ref 2 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415081.096439:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388900 going to sleep for 0 seconds
00010000:00080000:3.0:1786415081.107196:0:2408:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b23480 x1873191719929856/t0(0) o38->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 520/416 e 0 to 0 dl 1786415091 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:3.0:1786415081.107212:0:2408:0:(client.c:3112:ptlrpc_free_committed()) lustre-MDT0000-mdc-ffff9a560b251800: committing for last_committed 0 gen 1
00000100:00080000:3.0:1786415081.107221:0:2408:0:(import.c:1132:ptlrpc_connect_interpret()) lustre-MDT0000-mdc-ffff9a560b251800: connect to target with instance 1
00000001:01000000:2.0:1786415081.356930:0:2408:0:(integrity.c:236:obd_t10_performance_test()) lustre-MDT0000-mdc-ffff9a560b251800: T10 checksum algorithm t10ip512 speed = 3763 MB/s
00000001:01000000:0.0:1786415081.606647:0:2408:0:(integrity.c:236:obd_t10_performance_test()) lustre-MDT0000-mdc-ffff9a560b251800: T10 checksum algorithm t10ip4K speed = 3647 MB/s
00000001:01000000:1.0:1786415081.859230:0:2408:0:(integrity.c:236:obd_t10_performance_test()) lustre-MDT0000-mdc-ffff9a560b251800: T10 checksum algorithm t10crc512 speed = 634 MB/s
00010000:00010000:3.0:1786415081.882801:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000001:01000000:2.0:1786415082.110196:0:2408:0:(integrity.c:236:obd_t10_performance_test()) lustre-MDT0000-mdc-ffff9a560b251800: T10 checksum algorithm t10crc4K speed = 1278 MB/s
00000100:00080000:2.0:1786415082.110211:0:2408:0:(import.c:956:ptlrpc_connect_set_flags()) lustre-MDT0000-mdc-ffff9a560b251800: Resetting ns_connect_flags to server flags: 0xae7a7e7be344d3b8
00000100:00080000:2.0:1786415082.110236:0:2408:0:(import.c:1213:ptlrpc_connect_interpret()) connected to replayable target: lustre-MDT0000_UUID
00000100:00080000:2.0:1786415082.110241:0:2408:0:(import.c:65:import_set_state_nolock()) ffff9a560bf38800 lustre-MDT0000_UUID: changing import state from CONNECTING to FULL
00000100:00080000:2.0:1786415082.110816:0:2408:0:(recover.c:191:ptlrpc_wake_delayed()) @@@ waking (set 00000000031c0ada):  req@ffff9a5609b22d80 x1873191719929984/t0(0) o41->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 0 ref 1 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000100:00080000:2.0:1786415082.110898:0:2408:0:(recover.c:191:ptlrpc_wake_delayed()) @@@ waking (set 00000000d77804b6):  req@ffff9a5609b23b80 x1873191719930368/t0(0) o41->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 0 ref 2 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415082.110920:0:2408:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b23480 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719929856:192.168.204.144@tcp:38:
00000100:00100000:1.0:1786415082.110960:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388900 going to sleep for 0 seconds
00010000:00080000:2.0:1786415082.110960:0:2408:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b23800 x1873191719930112/t0(0) o38->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 520/416 e 0 to 0 dl 1786415091 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415082.110968:0:2408:0:(client.c:3112:ptlrpc_free_committed()) lustre-MDT0001-mdc-ffff9a560b251800: committing for last_committed 0 gen 1
00000100:00080000:2.0:1786415082.110977:0:2408:0:(import.c:1132:ptlrpc_connect_interpret()) lustre-MDT0001-mdc-ffff9a560b251800: connect to target with instance 1
00000100:00080000:2.0:1786415082.110984:0:2408:0:(import.c:956:ptlrpc_connect_set_flags()) lustre-MDT0001-mdc-ffff9a560b251800: Resetting ns_connect_flags to server flags: 0xae7a7e7be344d3b8
00000100:00080000:2.0:1786415082.110990:0:2408:0:(import.c:1213:ptlrpc_connect_interpret()) connected to replayable target: lustre-MDT0001_UUID
00000100:00080000:2.0:1786415082.110992:0:2408:0:(import.c:65:import_set_state_nolock()) ffff9a560bf3e800 lustre-MDT0001_UUID: changing import state from CONNECTING to FULL
00000100:00100000:1.0:1786415082.111061:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388900 going to sleep for 11 seconds
00000100:00080000:0.0:1786415082.111947:0:2408:0:(recover.c:191:ptlrpc_wake_delayed()) @@@ waking (set 00000000031c0ada):  req@ffff9a5609b20380 x1873191719930240/t0(0) o41->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 0 ref 1 fl Rpc:WQU/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:0.0:1786415082.111959:0:2408:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b23800 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719930112:192.168.204.144@tcp:38:
00010000:00080000:1.0:1786415082.114839:0:6181:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b23b80 x1873191719930368/t0(0) o41->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 1786415098 ref 2 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415082.114857:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b23b80 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719930368:192.168.204.144@tcp:41:
00800000:00000004:1.0:1786415082.114877:0:6181:0:(obd_class.h:1117:obd_statfs()) lustre-MDT0000-mdc-ffff9a560b251800: update ffff9a5604aab7d0 cache blocks 317403/353529 objects 1023723/1024000
00000080:00000004:1.0:1786415082.114884:0:6181:0:(obd_class.h:1117:obd_statfs()) lustre-clilmv-ffff9a560b251800: update ffff9a5604aa9e10 cache blocks 317403/353529 objects 1023723/1024000
00000100:00100000:1.0:1786415082.114917:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e39f80 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719930496:192.168.204.144@tcp:40:
00000100:00100000:1.0:1786415082.115009:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5609cabe40 going to sleep for 11 seconds
00010000:00080000:3.0:1786415082.119542:0:2412:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b20380 x1873191719930240/t0(0) o41->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 1786415098 ref 1 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:3.0:1786415082.119744:0:2412:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b20380 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_01:60eb3b9e-419f-472b-bd9c-bc0c604dab29:0:1873191719930240:192.168.204.144@tcp:41:
00010000:00080000:3.0:1786415082.119774:0:2412:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b22d80 x1873191719929984/t0(0) o41->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 1786415098 ref 1 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:3.0:1786415082.119785:0:2412:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b22d80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_01:60eb3b9e-419f-472b-bd9c-bc0c604dab29:0:1873191719929984:192.168.204.144@tcp:41:
00010000:00080000:1.0:1786415082.121930:0:6181:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609e39f80 x1873191719930496/t0(0) o40->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 440/448 e 0 to 0 dl 1786415098 ref 2 fl Rpc:RQU/209/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415082.125910:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e39f80 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719930496:192.168.204.144@tcp:40:
00000080:00000004:1.0:1786415082.125948:0:6181:0:(llite_lib.c:622:client_common_fill_super()) rootfid [0x200000007:0x1:0x0]
00000080:00100000:1.0:1786415082.125981:0:6181:0:(llite_lib.c:784:client_common_fill_super()) ocd_connect_flags: 0xa0467af2e3640038 ocd_version: 34682930 ocd_grant: 2146304
00020000:01000000:1.0:1786415082.125985:0:6181:0:(lov_obd.c:186:lov_connect()) connect #0
00000020:00000080:1.0:1786415082.125999:0:6181:0:(genops.c:1406:class_connect()) connect: client 60eb3b9e-419f-472b-bd9c-bc0c604dab29, cookie 0x85418d5ef8bb358d
00000020:00000080:1.0:1786415082.126017:0:6181:0:(genops.c:1406:class_connect()) connect: client LOV_OSC_UUID, cookie 0x85418d5ef8bb3594
00000100:00080000:1.0:1786415082.126027:0:6181:0:(import.c:510:import_select_connection()) lustre-OST0000-osc-ffff9a560b251800: connect to NID 192.168.204.144@tcp last attempt 0
00000100:00080000:1.0:1786415082.126039:0:6181:0:(import.c:624:import_select_connection()) lustre-OST0000-osc-ffff9a560b251800: import ffff9a560bf3a800 using connection 192.168.204.144@tcp/192.168.204.144@tcp
00000008:00100000:1.0:1786415082.126052:0:6181:0:(osc_request.c:3829:osc_reconnect()) ocd_connect_flags: 0xa0467af2e3640038 ocd_version: 34682930 ocd_grant: 134217728, lost: 0.
00000100:00100000:1.0:1786415082.126092:0:6181:0:(import.c:826:ptlrpc_connect_import_locked()) @@@ (re)connect request (timeout 5)  req@ffff9a5609e39f80 x1873191719930624/t0(0) o8->lustre-OST0000-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 520/544 e 0 to 0 dl 0 ref 1 fl New:NQU/0/ffffffff rc 0/-1 job:'' uid:4294967295 gid:4294967295 projid:4294967295
00000100:00100000:0.0:1786415082.126170:0:2408:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e39f80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719930624:192.168.204.144@tcp:8:
00000100:00080000:1.0:1786415082.126876:0:6181:0:(pinger.c:373:ptlrpc_pinger_add_import()) adding pingable import 60eb3b9e-419f-472b-bd9c-bc0c604dab29->lustre-OST0000_UUID
00020000:01000000:1.0:1786415082.126899:0:6181:0:(lov_obd.c:159:lov_connect_osc()) Connected tgt idx 0 lustre-OST0000_UUID (lustre-OST0000-osc-ffff9a560b251800) active
00000020:00000080:1.0:1786415082.126930:0:6181:0:(genops.c:1406:class_connect()) connect: client LOV_OSC_UUID, cookie 0x85418d5ef8bb359b
00000100:00080000:1.0:1786415082.126937:0:6181:0:(import.c:510:import_select_connection()) lustre-OST0001-osc-ffff9a560b251800: connect to NID 192.168.204.144@tcp last attempt 0
00000100:00080000:1.0:1786415082.126945:0:6181:0:(import.c:624:import_select_connection()) lustre-OST0001-osc-ffff9a560b251800: import ffff9a560b250000 using connection 192.168.204.144@tcp/192.168.204.144@tcp
00000008:00100000:1.0:1786415082.126952:0:6181:0:(osc_request.c:3829:osc_reconnect()) ocd_connect_flags: 0xa0467af2e3640038 ocd_version: 34682930 ocd_grant: 134217728, lost: 0.
00000100:00100000:1.0:1786415082.126968:0:6181:0:(import.c:826:ptlrpc_connect_import_locked()) @@@ (re)connect request (timeout 5)  req@ffff9a5609e38700 x1873191719930752/t0(0) o8->lustre-OST0001-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 520/544 e 0 to 0 dl 0 ref 1 fl New:NQU/0/ffffffff rc 0/-1 job:'' uid:4294967295 gid:4294967295 projid:4294967295
00000100:00100000:0.0:1786415082.127175:0:2408:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e38700 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719930752:192.168.204.144@tcp:8:
00000100:00100000:3.0:1786415082.127586:0:2411:0:(ptlrpcd.c:344:ptlrpcd_check()) transfer 1 async RPCs [1->0]
00000100:00100000:3.0:1786415082.127599:0:2411:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_00:596bd973-15df-48a1-9a23-d267a70f4eca:2411:1873191719930880:192.168.204.144@tcp:400:
00000100:00080000:1.0:1786415082.127797:0:6181:0:(pinger.c:373:ptlrpc_pinger_add_import()) adding pingable import 60eb3b9e-419f-472b-bd9c-bc0c604dab29->lustre-OST0001_UUID
00020000:01000000:1.0:1786415082.127813:0:6181:0:(lov_obd.c:159:lov_connect_osc()) Connected tgt idx 1 lustre-OST0001_UUID (lustre-OST0001-osc-ffff9a560b251800) active
00000100:00100000:1.0:1786415082.127862:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719931008:192.168.204.144@tcp:900:
00000100:00100000:1.0:1786415082.128018:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5609cab0c0 going to sleep for 11 seconds
00010000:00080000:2.0:1786415082.132641:0:2408:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609e39f80 x1873191719930624/t0(0) o8->lustre-OST0000-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 520/416 e 0 to 0 dl 1786415092 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415082.132657:0:2408:0:(client.c:3112:ptlrpc_free_committed()) lustre-OST0000-osc-ffff9a560b251800: committing for last_committed 0 gen 1
00000100:00080000:2.0:1786415082.132668:0:2408:0:(import.c:1132:ptlrpc_connect_interpret()) lustre-OST0000-osc-ffff9a560b251800: connect to target with instance 1
00000100:00080000:2.0:1786415082.132678:0:2408:0:(import.c:956:ptlrpc_connect_set_flags()) lustre-OST0000-osc-ffff9a560b251800: Resetting ns_connect_flags to server flags: 0xa0427af2e3440038
00000100:00080000:2.0:1786415082.132691:0:2408:0:(import.c:1213:ptlrpc_connect_interpret()) connected to replayable target: lustre-OST0000_UUID
00000100:00080000:2.0:1786415082.132694:0:2408:0:(import.c:65:import_set_state_nolock()) ffff9a560bf3a800 lustre-OST0000_UUID: changing import state from CONNECTING to FULL
00020000:01000000:2.0:1786415082.132702:0:2408:0:(lov_obd.c:398:lov_set_osc_active()) Marking OSC lustre-OST0000_UUID active
00000080:00000004:2.0:1786415082.132706:0:2408:0:(lcommon_misc.c:94:cl_ocd_update()) Changing connect_flags: 0xa0467af2e3640038 -> 0xa0427af2e3440038
00000080:00080000:2.0:1786415082.132711:0:2408:0:(lcommon_misc.c:56:cl_init_ea_size()) updating def/max_easize: 72/65536
00000100:00100000:2.0:1786415082.132970:0:2408:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e39f80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719930624:192.168.204.144@tcp:8:
00010000:00080000:2.0:1786415082.134700:0:2408:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609e38700 x1873191719930752/t0(0) o8->lustre-OST0001-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 520/416 e 0 to 0 dl 1786415092 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415082.134714:0:2408:0:(client.c:3112:ptlrpc_free_committed()) lustre-OST0001-osc-ffff9a560b251800: committing for last_committed 0 gen 1
00000100:00100000:3.0:1786415082.134719:0:2411:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_00:596bd973-15df-48a1-9a23-d267a70f4eca:2411:1873191719930880:192.168.204.144@tcp:400:
00000100:00080000:2.0:1786415082.134720:0:2408:0:(import.c:1132:ptlrpc_connect_interpret()) lustre-OST0001-osc-ffff9a560b251800: connect to target with instance 1
00000100:00080000:2.0:1786415082.134728:0:2408:0:(import.c:956:ptlrpc_connect_set_flags()) lustre-OST0001-osc-ffff9a560b251800: Resetting ns_connect_flags to server flags: 0xa0427af2e3440038
00000100:00080000:2.0:1786415082.134738:0:2408:0:(import.c:1213:ptlrpc_connect_interpret()) connected to replayable target: lustre-OST0001_UUID
00000100:00080000:2.0:1786415082.134741:0:2408:0:(import.c:65:import_set_state_nolock()) ffff9a560b250000 lustre-OST0001_UUID: changing import state from CONNECTING to FULL
00020000:01000000:2.0:1786415082.134747:0:2408:0:(lov_obd.c:398:lov_set_osc_active()) Marking OSC lustre-OST0001_UUID active
00000080:00000004:2.0:1786415082.134761:0:2408:0:(lcommon_misc.c:94:cl_ocd_update()) Changing connect_flags: 0xa0427af2e3440038 -> 0xa0427af2e3440038
00000080:00080000:2.0:1786415082.134765:0:2408:0:(lcommon_misc.c:56:cl_init_ea_size()) updating def/max_easize: 72/65536
00000100:00100000:2.0:1786415082.134773:0:2408:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e38700 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_rcv:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2408:1873191719930752:192.168.204.144@tcp:8:
00010000:00080000:1.0:1786415082.135024:0:6181:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609e3a300 x1873191719931008/t0(0) o900->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:29/10 lens 264/248 e 0 to 0 dl 1786415098 ref 2 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415082.135036:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719931008:192.168.204.144@tcp:900:
00000100:00100000:1.0:1786415082.135122:0:6181:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719931136:192.168.204.144@tcp:33:
00000100:00100000:1.0:1786415082.135250:0:6181:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5609cab0c0 going to sleep for 11 seconds
00010000:00080000:1.0:1786415082.210170:0:6181:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609e3a300 x1873191719931136/t0(0) o33->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:23/10 lens 448/464 e 0 to 0 dl 1786415098 ref 2 fl Rpc:RQU/600/0 rc 0/0 job:'' uid:0 gid:0 projid:0
00000100:00100000:1.0:1786415082.210190:0:6181:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job mount.lustre:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6181:1873191719931136:192.168.204.144@tcp:33:
00000080:00200000:1.0:1786415082.210393:0:6181:0:(llite_lib.c:3364:ll_read_inode2()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8)
00000080:00200000:1.0:1786415082.210401:0:6181:0:(llite_lib.c:3127:ll_update_inode()) inode=[0x200000007:0x1:0x0], updating i_size 4096
00000080:02000400:1.0:1786415082.210476:0:6181:0:(llite_lib.c:1662:ll_fill_super()) Mounted lustre-client - version 2.17.56_50_gb0a9653
00000080:00000004:1.0:1786415082.219026:0:6181:0:(super25.c:181:lustre_fill_super()) 192.168.204.144@tcp:/lustre: Mount complete
00000080:00200008:0.0:1786415082.420849:0:6224:0:(file.c:6283:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff9a56041163c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:0.0:1786415082.420860:0:6224:0:(file.c:6178:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8),name=/
00000002:00010000:0.0:1786415082.420885:0:6224:0:(mdc_locks.c:1506:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:0.0:1786415082.420942:0:6224:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x0/0x0 rrc: 2 type: IBT flags: 0x10000000000000 pid: 6224 initiator: MDT0 lru_score: 0 lru_type: 0
00010000:00010000:0.0:1786415082.420952:0:6224:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000 ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 6224 initiator: MDT0 lru_score: 0 lru_type: 0
00010000:00010000:0.0:1786415082.420973:0:6224:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 3/1,0 mode: --/CR res: [0x200000007:0x1:0x0].0x0 bits 0x2/0x0 rrc: 2 type: IBT flags: 0x0 pid: 6224 initiator: MDT0 lru_score: 0 lru_type: 0
00000100:00100000:0.0:1786415082.420997:0:6224:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e4380 pname:cluuid:pid:xid:nid:opc:job mkdir:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6224:1873191719931264:192.168.204.144@tcp:101:
00000100:00100000:0.0:1786415082.421699:0:6224:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a560b4db840 going to sleep for 11 seconds
00000100:00100000:1.0:1786415082.444122:0:6224:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e4380 pname:cluuid:pid:xid:nid:opc:job mkdir:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6224:1873191719931264:192.168.204.144@tcp:101:
00010000:00010000:1.0:1786415082.444294:0:6224:0:(ldlm_request.c:759:ldlm_cli_enqueue_fini()) ### server returned different mode PR ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444307:0:6224:0:(ldlm_request.c:787:ldlm_cli_enqueue_fini()) ### client-side enqueue, new policy data ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444319:0:6224:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444336:0:6224:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444349:0:6224:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444357:0:6224:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444377:0:6224:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000002:00100000:1.0:1786415082.444391:0:6224:0:(mdc_locks.c:833:mdc_finish_enqueue()) @@@ op=0x8 disposition=0xb, status=0  req@ffff9a56090e4380 x1873191719931264/t0(0) o101->lustre-MDT0000-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 576/688 e 0 to 0 dl 1786415098 ref 1 fl Complete:RQU/600/0 rc 0/0 job:'' uid:0 gid:0 projid:0
00000002:00010000:1.0:1786415082.444406:0:6224:0:(mdc_locks.c:1357:mdc_finish_intent_lock()) ### matching against this ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444419:0:6224:0:(ldlm_lock.c:1539:ldlm_lock_match_with_skip()) ### not matched ns ffff9a56090a5800 type 13 mode 4 res 8589934599/1 (0 0)
00000080:00200000:1.0:1786415082.444426:0:6224:0:(namei.c:952:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0
00000080:00200000:1.0:1786415082.444453:0:6224:0:(llite_lib.c:3127:ll_update_inode()) inode=[0x200000007:0x1:0x0], updating i_size 4096
00000080:00010000:1.0:1786415082.444458:0:6224:0:(llite_internal.h:1988:ll_set_lock_data()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8) for lock 0x85418d5ef8bb35a2
00000080:00010000:1.0:1786415082.444463:0:6224:0:(dcache.c:255:ll_lookup_finish_locks()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8)
00000080:00010000:1.0:1786415082.444467:0:6224:0:(dcache.c:152:ll_intent_drop_lock()) releasing lock with cookie 0x85418d5ef8bb35a2 from it ffffa833446dbcb8
00010000:00010000:1.0:1786415082.444470:0:6224:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:1.0:1786415082.444480:0:6224:0:(ldlm_lock.c:912:ldlm_lock_decref_internal()) ### add lock into lru list ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 1 lru_type: 0
00010000:00010000:2.0:1786415082.904842:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415083.930800:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000080:00200008:2.0:1786415084.707929:0:6277:0:(file.c:6283:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff9a56041163c8), request_mask 2047, flags 0, foreign 0
00000080:00200000:2.0:1786415084.707942:0:6277:0:(file.c:6178:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8),name=/
00000002:00010000:2.0:1786415084.707970:0:6277:0:(mdc_locks.c:1506:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:2.0:1786415084.707992:0:6277:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 2/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 3 type: IBT gid 0 flags: 0x0 nid: local remote: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 1 lru_type: 0
00010000:00010000:2.0:1786415084.708009:0:6277:0:(ldlm_lock.c:1522:ldlm_lock_match_with_skip()) ### matched (0 0) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 2/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 1 lru_type: 0
00000080:00200000:2.0:1786415084.708017:0:6277:0:(namei.c:952:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1
00000080:00010000:2.0:1786415084.708036:0:6277:0:(dcache.c:255:ll_lookup_finish_locks()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8)
00000080:00010000:2.0:1786415084.708040:0:6277:0:(llite_internal.h:1988:ll_set_lock_data()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8) for lock 0x85418d5ef8bb35a2
00000080:00010000:2.0:1786415084.708049:0:6277:0:(dcache.c:152:ll_intent_drop_lock()) releasing lock with cookie 0x85418d5ef8bb35a2 from it ffffa83344703cb8
00010000:00010000:2.0:1786415084.708053:0:6277:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 1 lru_type: 0
00010000:00010000:2.0:1786415084.708069:0:6277:0:(ldlm_lock.c:912:ldlm_lock_decref_internal()) ### add lock into lru list ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 2 lru_type: 1
00000080:00200008:2.0:1786415084.708763:0:6277:0:(file.c:6283:ll_getattr_dentry()) START file /:[0x200000007:0x1:0x0](ffff9a56041163c8), request_mask 0, flags 0, foreign 0
00000080:00200000:2.0:1786415084.708769:0:6277:0:(file.c:6178:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8),name=/
00000002:00010000:2.0:1786415084.708779:0:6277:0:(mdc_locks.c:1506:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:2.0:1786415084.708789:0:6277:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 2/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 3 type: IBT gid 0 flags: 0x0 nid: local remote: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 2 lru_type: 1
00010000:00010000:2.0:1786415084.708798:0:6277:0:(ldlm_lock.c:1522:ldlm_lock_match_with_skip()) ### matched (0 0) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 2/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 2 lru_type: 1
00000080:00200000:2.0:1786415084.708805:0:6277:0:(namei.c:952:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1
00000080:00010000:2.0:1786415084.708810:0:6277:0:(dcache.c:255:ll_lookup_finish_locks()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8)
00000080:00010000:2.0:1786415084.708814:0:6277:0:(llite_internal.h:1988:ll_set_lock_data()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8) for lock 0x85418d5ef8bb35a2
00000080:00010000:2.0:1786415084.708819:0:6277:0:(dcache.c:152:ll_intent_drop_lock()) releasing lock with cookie 0x85418d5ef8bb35a2 from it ffffa83344703cb8
00010000:00010000:2.0:1786415084.708822:0:6277:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 2 lru_type: 1
00010000:00010000:2.0:1786415084.708829:0:6277:0:(ldlm_lock.c:912:ldlm_lock_decref_internal()) ### add lock into lru list ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 3 lru_type: 1
00000080:00200000:2.0:1786415084.708923:0:6277:0:(file.c:6178:ll_inode_revalidate()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8),name=/
00000002:00010000:2.0:1786415084.708932:0:6277:0:(mdc_locks.c:1506:mdc_intent_lock()) (name: ,[0x200000007:0x1:0x0]) in obj [0x200000007:0x1:0x0], intent: getattr flags 0
00010000:00010000:2.0:1786415084.708939:0:6277:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 2/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 3 type: IBT gid 0 flags: 0x0 nid: local remote: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 3 lru_type: 1
00010000:00010000:2.0:1786415084.708959:0:6277:0:(ldlm_lock.c:1522:ldlm_lock_match_with_skip()) ### matched (0 0) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 lrc: 2/1,0 mode: PR/PR res: [0x200000007:0x1:0x0].0x0 bits 0x13/0x0 rrc: 2 type: IBT gid 0 flags: 0x0 nid: local remote: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 3 lru_type: 1
00000080:00200000:2.0:1786415084.708966:0:6277:0:(namei.c:952:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1
00000080:00010000:2.0:1786415084.708970:0:6277:0:(dcache.c:255:ll_lookup_finish_locks()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8)
00000080:00010000:2.0:1786415084.708972:0:6277:0:(llite_internal.h:1988:ll_set_lock_data()) setting l_data to inode [0x200000007:0x1:0x0](ffff9a56041163c8) for lock 0x85418d5ef8bb35a2
00000080:00010000:2.0:1786415084.708976:0:6277:0:(dcache.c:152:ll_intent_drop_lock()) releasing lock with cookie 0x85418d5ef8bb35a2 from it ffffa83344703bb0
00010000:00010000:2.0:1786415084.708979:0:6277:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(PR) ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 3 lru_type: 1
00010000:00010000:2.0:1786415084.708986:0:6277:0:(ldlm_lock.c:912:ldlm_lock_decref_internal()) ### add lock into lru list ns: lustre-MDT0000-mdc-ffff9a560b251800 lock: ffff9a5600030400/0x85418d5ef8bb35a2 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: 0x5eec1eb7faf85877 expref: -99 pid: 6224 timeout: 0 lvb_type: 0 lru_score: 4 lru_type: 1
00000080:00200000:2.0:1786415084.708994:0:6277:0:(file.c:6659:ll_inode_permission()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8), inode mode 41ed mask 44
00000080:00200008:2.0:1786415084.709000:0:6277:0:(file.c:1090:ll_file_open()) START file /:[0x200000007:0x1:0x0](ffff9a56041163c8), flags 100000
00000080:00200080:2.0:1786415084.709179:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.709194:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1c400 uarg=0000000000000000
00800000:00000004:2.0:1786415084.709201:0:6277:0:(obd_class.h:1091:obd_statfs()) lustre-MDT0000-mdc-ffff9a560b251800: age 383, max_age 385
00000100:00100000:2.0:1786415084.709242:0:6277:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b22680 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931392:192.168.204.144@tcp:41:
00000100:00100000:2.0:1786415084.709346:0:6277:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388c00 going to sleep for 11 seconds
00000100:00100000:3.0:1786415084.716237:0:6277:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b22680 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931392:192.168.204.144@tcp:41:
00800000:00000004:3.0:1786415084.716266:0:6277:0:(obd_class.h:1117:obd_statfs()) lustre-MDT0000-mdc-ffff9a560b251800: update ffff9a5604aab7d0 cache blocks 317403/353529 objects 1023723/1024000
00000080:00200080:2.0:1786415084.716586:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.716614:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00800000:00000004:2.0:1786415084.716622:0:6277:0:(obd_class.h:1091:obd_statfs()) lustre-MDT0001-mdc-ffff9a560b251800: age -618, max_age 385
00000100:00100000:2.0:1786415084.716654:0:6277:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b23100 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931520:192.168.204.144@tcp:41:
00000100:00100000:2.0:1786415084.716739:0:6277:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388840 going to sleep for 11 seconds
00010000:00080000:2.0:1786415084.726340:0:6277:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b23100 x1873191719931520/t0(0) o41->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/368 e 0 to 0 dl 1786415100 ref 2 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415084.726363:0:6277:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b23100 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931520:192.168.204.144@tcp:41:
00800000:00000004:2.0:1786415084.726388:0:6277:0:(obd_class.h:1117:obd_statfs()) lustre-MDT0001-mdc-ffff9a560b251800: update ffff9a5604aad190 cache blocks 317443/353529 objects 1023743/1024000
00000080:00200080:2.0:1786415084.726459:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726475:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726483:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726487:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726491:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726495:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726499:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726503:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726507:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726511:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726514:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726518:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726522:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726525:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726529:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726533:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726536:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726540:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726544:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726548:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726552:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726862:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726869:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726873:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726878:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726882:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726886:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726889:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726894:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726897:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726902:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726905:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726909:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726913:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726916:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726920:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726924:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.726927:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.726931:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727011:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727018:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727033:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727039:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727043:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727048:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727051:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727062:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727067:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727071:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727075:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727079:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727083:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727087:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727090:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727094:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727098:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727102:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727105:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727110:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727113:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727117:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727120:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727124:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727128:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727132:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727135:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727139:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727142:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727147:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727150:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727155:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727164:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727170:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727174:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727178:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727181:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727185:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727189:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727193:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727196:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727200:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727204:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727207:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727211:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727215:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727219:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727223:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727226:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727231:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727234:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727238:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727242:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727246:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727250:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727254:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727257:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727339:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727345:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727349:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727353:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727357:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727360:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727364:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727368:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727372:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727376:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727380:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727383:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727387:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727391:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727395:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727398:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727402:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727406:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727410:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727414:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727418:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727421:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727425:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727429:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727433:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727685:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727694:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727698:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727702:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00800000:00000080:2.0:1786415084.727706:0:6277:0:(lmv_obd.c:934:lmv_iocontrol()) lustre-clilmv-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.727710:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.727716:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00020000:00000004:2.0:1786415084.727724:0:6277:0:(obd_class.h:1091:obd_statfs()) lustre-OST0000-osc-ffff9a560b251800: age -618, max_age 385
00000100:00100000:2.0:1786415084.727765:0:6277:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b23100 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931648:192.168.204.144@tcp:13:
00000100:00100000:2.0:1786415084.727840:0:6277:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388840 going to sleep for 11 seconds
00010000:00080000:2.0:1786415084.733272:0:6277:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b23100 x1873191719931648/t0(0) o13->lustre-OST0000-osc-ffff9a560b251800@192.168.204.144@tcp:7/4 lens 224/368 e 0 to 0 dl 1786415100 ref 2 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415084.733292:0:6277:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b23100 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931648:192.168.204.144@tcp:13:
00020000:00000004:2.0:1786415084.733315:0:6277:0:(obd_class.h:1117:obd_statfs()) lustre-OST0000-osc-ffff9a560b251800: update ffff9a560b4e0450 cache blocks 901369/958279 objects 261775/262144
00000080:00200080:2.0:1786415084.733429:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.733447:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00020000:00000004:2.0:1786415084.733455:0:6277:0:(obd_class.h:1091:obd_statfs()) lustre-OST0001-osc-ffff9a560b251800: age -618, max_age 385
00000100:00100000:2.0:1786415084.733485:0:6277:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b23100 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931776:192.168.204.144@tcp:13:
00000100:00100000:2.0:1786415084.733642:0:6277:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5610388840 going to sleep for 11 seconds
00010000:00080000:2.0:1786415084.737264:0:6277:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a5609b23100 x1873191719931776/t0(0) o13->lustre-OST0001-osc-ffff9a560b251800@192.168.204.144@tcp:7/4 lens 224/368 e 0 to 0 dl 1786415100 ref 2 fl Rpc:RQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:2.0:1786415084.737283:0:6277:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b23100 pname:cluuid:pid:xid:nid:opc:job lfs:60eb3b9e-419f-472b-bd9c-bc0c604dab29:6277:1873191719931776:192.168.204.144@tcp:13:
00020000:00000004:2.0:1786415084.737306:0:6277:0:(obd_class.h:1117:obd_statfs()) lustre-OST0001-osc-ffff9a560b251800: update ffff9a560a380450 cache blocks 901369/958279 objects 261775/262144
00000080:00200080:2.0:1786415084.737420:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737461:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737470:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737475:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737479:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737483:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737487:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737491:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737494:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737498:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737502:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737505:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737509:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737512:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737516:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737519:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737523:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737527:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737531:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737534:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737538:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737541:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737545:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737548:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737552:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737556:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737604:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737611:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737616:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737620:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737624:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737627:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737631:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737635:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737639:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737642:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737646:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737649:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737653:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737656:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737660:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737663:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737668:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737671:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737675:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737678:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737682:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737685:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737689:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737692:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737696:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737706:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737710:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737714:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737718:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737721:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737725:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737729:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737732:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737735:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737739:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737742:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737746:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737750:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737753:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737756:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737759:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737762:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737766:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737769:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737772:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737775:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737779:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737782:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737785:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737789:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737799:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737803:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737808:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737811:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737816:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737819:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737824:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737827:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737831:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737835:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737839:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737842:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737846:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737849:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737853:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737856:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737860:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737864:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737868:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737872:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737876:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737879:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737883:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737886:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737890:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737899:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737904:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737907:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737911:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737915:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737919:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737922:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.737926:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.737929:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739059:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739077:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739085:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739089:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739092:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739095:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739098:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739101:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739104:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739106:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739109:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739112:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739115:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739117:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200080:2.0:1786415084.739120:0:6277:0:(dir.c:2003:ll_dir_ioctl()) VFS Op:inode=[0x200000007:0x1:0x0](ffff9a56041163c8) cmd=c00866a4 arg=7ffdae153330
00020000:00000080:2.0:1786415084.739123:0:6277:0:(lov_obd.c:976:lov_iocontrol()) lustre-clilov-ffff9a560b251800: cmd=c00866a4 len=592 karg=ffff9a5609e1ec00 uarg=0000000000000000
00000080:00200008:2.0:1786415084.739146:0:6277:0:(file.c:479:ll_file_release()) START file /:[0x200000007:0x1:0x0](ffff9a56041163c8), flags 100000
00010000:00010000:2.0:1786415084.952514:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415085.976054:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415087.000275:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:1.0:1786415087.586359:0:2410:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e7b80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:596bd973-15df-48a1-9a23-d267a70f4eca:2410:1873191719931904:192.168.204.144@tcp:400:
00000100:00100000:1.0:1786415087.587360:0:2410:0:(ptlrpcd.c:344:ptlrpcd_check()) transfer 1 async RPCs [0->1]
00000100:00100000:1.0:1786415087.587373:0:2410:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e6680 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2410:1873191719932032:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415087.591524:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e4000 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932288:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415087.592003:0:2409:0:(ptlrpcd.c:344:ptlrpcd_check()) transfer 2 async RPCs [1->0]
00000100:00100000:0.0:1786415087.592011:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e7100 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932160:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415087.592038:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e5880 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932416:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415087.594908:0:2410:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e7b80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:596bd973-15df-48a1-9a23-d267a70f4eca:2410:1873191719931904:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415087.596560:0:2410:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e6680 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_01:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2410:1873191719932032:192.168.204.144@tcp:400:
00010000:00080000:1.0:1786415087.601759:0:2409:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a56090e7100 x1873191719932160/t0(0) o400->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/224 e 0 to 0 dl 1786415103 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415087.601792:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e7100 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932160:192.168.204.144@tcp:400:
00010000:00080000:1.0:1786415087.601811:0:2409:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a56090e5880 x1873191719932416/t0(0) o400->lustre-OST0001-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 0 dl 1786415103 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415087.601820:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e5880 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932416:192.168.204.144@tcp:400:
00010000:00080000:1.0:1786415087.601834:0:2409:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a56090e4000 x1873191719932288/t0(0) o400->lustre-OST0000-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 0 dl 1786415103 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:1.0:1786415087.601842:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e4000 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932288:192.168.204.144@tcp:400:
00000001:02000400:3.0:1786415088.005640:0:6472:0:(debug.c:654:libcfs_debug_mark_buffer()) DEBUG MARKER: Using TIMEOUT=20
00010000:00010000:0.0:1786415088.040294:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415089.047759:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:0.0:1786415090.075553:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415091.096172:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:0.0:1786415092.120100:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:0.0:1786415092.698206:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e6680 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:596bd973-15df-48a1-9a23-d267a70f4eca:2409:1873191719932544:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415092.698336:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e6a00 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932800:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415092.698380:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e4000 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719933056:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415092.698415:0:2409:0:(ptlrpcd.c:344:ptlrpcd_check()) transfer 2 async RPCs [1->0]
00000100:00100000:0.0:1786415092.698422:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e7b80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932672:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415092.698447:0:2409:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a56090e5180 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932928:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415092.701977:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e7b80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932672:192.168.204.144@tcp:400:
00010000:00080000:0.0:1786415092.702015:0:2409:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a56090e5180 x1873191719932928/t0(0) o400->lustre-OST0000-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 0 dl 1786415108 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:0.0:1786415092.702029:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e5180 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932928:192.168.204.144@tcp:400:
00010000:00080000:0.0:1786415092.702041:0:2409:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a56090e6a00 x1873191719932800/t0(0) o400->lustre-MDT0001-mdc-ffff9a560b251800@192.168.204.144@tcp:12/10 lens 224/224 e 0 to 0 dl 1786415108 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:0.0:1786415092.702051:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e6a00 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719932800:192.168.204.144@tcp:400:
00010000:00080000:0.0:1786415092.702063:0:2409:0:(ldlm_request.c:1671:ldlm_cli_update_pool()) @@@ Zero SLV or limit found (SLV=0, limit=46162)  req@ffff9a56090e4000 x1873191719933056/t0(0) o400->lustre-OST0001-osc-ffff9a560b251800@192.168.204.144@tcp:28/4 lens 224/224 e 0 to 0 dl 1786415108 ref 1 fl Rpc:RNQU/200/0 rc 0/0 job:'' uid:0 gid:0 projid:4294967295
00000100:00100000:0.0:1786415092.702072:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e4000 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:60eb3b9e-419f-472b-bd9c-bc0c604dab29:2409:1873191719933056:192.168.204.144@tcp:400:
00000100:00100000:0.0:1786415092.713907:0:2409:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a56090e6680 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_00_00:596bd973-15df-48a1-9a23-d267a70f4eca:2409:1873191719932544:192.168.204.144@tcp:400:
00010000:00010000:1.0:1786415093.144686:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:0.0:1786415094.169105:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:0.0:1786415094.275766:0:2402:0:(events.c:340:request_in_callback()) peer: 12345-192.168.204.144@tcp (source: 12345-192.168.204.144@tcp)
00000100:00100000:0.0:1786415094.275818:0:2402:0:(events.c:340:request_in_callback()) peer: 12345-192.168.204.144@tcp (source: 12345-192.168.204.144@tcp)
00000100:00100000:0.0:1786415094.275884:0:6183:0:(service.c:2301:ptlrpc_server_handle_req_in()) unwrap req x1873191745160192
00000100:00100000:0.0:1786415094.275896:0:6183:0:(service.c:2366:ptlrpc_server_handle_req_in()) got req x1873191745160192
00000100:00100000:0.0:1786415094.275956:0:6183:0:(service.c:2301:ptlrpc_server_handle_req_in()) unwrap req x1873191745160320
00000100:00100000:0.0:1786415094.275960:0:6183:0:(service.c:2366:ptlrpc_server_handle_req_in()) got req x1873191745160320
00000100:00100000:0.0:1786415094.275978:0:6183:0:(nrs_fifo.c:152:nrs_fifo_req_get()) NRS start fifo request from 12345-192.168.204.144@tcp, seq: 0
00000100:00100000:0.0:1786415094.275988:0:6183:0:(service.c:2552:ptlrpc_server_handle_request()) Handling RPC req@ffff9a56090e6680 pname:cluuid+ref:pid:xid:nid:opc:job ldlm_cb00_001:596bd973-15df-48a1-9a23-d267a70f4eca+4:10350:x1873191745160192:12345-192.168.204.144@tcp:104:
00010000:00010000:0.0:1786415094.276008:0:6183:0:(ldlm_lockd.c:2506:ldlm_callback_handler()) ### blocking ast  ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1400000000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:0.0:1786415094.276097:0:6183:0:(ldlm_lockd.c:2189:__ldlm_bl_to_thread()) added 1/1 locks to priority blp list, 1 blwis in pool
00000100:00100000:0.0:1786415094.276104:0:6183:0:(service.c:2610:ptlrpc_server_handle_request()) Handled RPC req@ffff9a56090e6680 pname:cluuid+ref:pid:xid:nid:opc:job ldlm_cb00_001:596bd973-15df-48a1-9a23-d267a70f4eca+4:10350:x1873191745160192:12345-192.168.204.144@tcp:104: Request processed in 118us (354us total) trans 0 rc 0/0
00000100:00100000:0.0:1786415094.276129:0:6183:0:(nrs_fifo.c:211:nrs_fifo_req_stop()) NRS stop fifo request from 12345-192.168.204.144@tcp, seq: 0
00000100:00100000:0.0:1786415094.276151:0:6183:0:(nrs_fifo.c:152:nrs_fifo_req_get()) NRS start fifo request from 12345-192.168.204.144@tcp, seq: 1
00000100:00100000:0.0:1786415094.276158:0:6183:0:(service.c:2552:ptlrpc_server_handle_request()) Handling RPC req@ffff9a56090e4000 pname:cluuid+ref:pid:xid:nid:opc:job ldlm_cb00_001:596bd973-15df-48a1-9a23-d267a70f4eca+3:10350:x1873191745160320:12345-192.168.204.144@tcp:104:
00010000:00010000:0.0:1786415094.276166:0:6183:0:(ldlm_lockd.c:2506:ldlm_callback_handler()) ### blocking ast  ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1400000000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:0.0:1786415094.276209:0:6183:0:(ldlm_lockd.c:2189:__ldlm_bl_to_thread()) added 1/2 locks to priority blp list, 2 blwis in pool
00010000:00010000:3.0:1786415094.276455:0:6192:0:(ldlm_lockd.c:2801:ldlm_bl_get_work()) Got 1 locks of 1 total in blp.  (1 blwis in pool)
00010000:00010000:3.0:1786415094.276465:0:6192:0:(ldlm_lockd.c:1907:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1400000000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276474:0:6192:0:(ldlm_lockd.c:1921:ldlm_handle_bl_callback()) Lock ffff9a5610cc5c00 already unused, calling callback (ffffffffc1109c20)
10000000:00010000:3.0:1786415094.276479:0:6192:0:(mgc_request.c:922:mgc_blocking_ast()) ### MGC blocking CB ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1400400000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276488:0:6192:0:(ldlm_request.c:1391:ldlm_cli_cancel_local()) ### client-side cancel ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1408400000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:00010000:3.0:1786415094.276503:0:6192:0:(mgc_request.c:928:mgc_blocking_ast()) ### MGC cancel CB ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1409400000000 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:01000000:3.0:1786415094.276511:0:6192:0:(mgc_request.c:932:mgc_blocking_ast()) Lock res [0x65727473756c:0x0:0x0].0x0 (lustre)
00010000:00010000:3.0:1786415094.276560:0:6192:0:(ldlm_request.c:1439:__ldlm_pack_lock()) ### packing ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 2/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x5c09400000020 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276621:0:6192:0:(ldlm_request.c:1498:ldlm_cancel_pack()) 1 locks packed
00010000:00010000:3.0:1786415094.276643:0:6192:0:(ldlm_lockd.c:1933:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 1/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x5c09400000020 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276651:0:6192:0:(ldlm_lock.c:193:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: MGC192.168.204.144@tcp lock: ffff9a5610cc5c00/0x85418d5ef8bb3539 lrc: 0/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x5c09400000020 nid: local remote: 0x5eec1eb7faf8583f expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276669:0:6192:0:(ldlm_lockd.c:2801:ldlm_bl_get_work()) Got 1 locks of 0 total in blp.  (0 blwis in pool)
00010000:00010000:3.0:1786415094.276672:0:6192:0:(ldlm_lockd.c:1907:ldlm_handle_bl_callback()) ### client blocking AST callback handler ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1400000000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276677:0:6192:0:(ldlm_lockd.c:1921:ldlm_handle_bl_callback()) Lock ffff9a5610cc4800 already unused, calling callback (ffffffffc1109c20)
10000000:00010000:3.0:1786415094.276680:0:6192:0:(mgc_request.c:922:mgc_blocking_ast()) ### MGC blocking CB ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1400400000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276686:0:6192:0:(ldlm_request.c:1391:ldlm_cli_cancel_local()) ### client-side cancel ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1408400000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:00010000:3.0:1786415094.276691:0:6192:0:(mgc_request.c:928:mgc_blocking_ast()) ### MGC cancel CB ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 3/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1409400000000 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:01000000:3.0:1786415094.276696:0:6192:0:(mgc_request.c:932:mgc_blocking_ast()) Lock res [0x65727473756c:0x0:0x0].0x0 (lustre)
00010000:00010000:3.0:1786415094.276716:0:6192:0:(ldlm_request.c:1439:__ldlm_pack_lock()) ### packing ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 2/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x5c09400000020 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276723:0:6192:0:(ldlm_request.c:1498:ldlm_cancel_pack()) 1 locks packed
00010000:00010000:3.0:1786415094.276729:0:6192:0:(ldlm_lockd.c:1933:ldlm_handle_bl_callback()) ### client blocking callback handler END ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 1/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x5c09400000020 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276734:0:6192:0:(ldlm_lock.c:193:ldlm_lock_put()) ### final lock_put on destroyed lock, freeing it. ns: MGC192.168.204.144@tcp lock: ffff9a5610cc4800/0x85418d5ef8bb3532 lrc: 0/0,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x5c09400000020 nid: local remote: 0x5eec1eb7faf85838 expref: -99 pid: 6181 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.276749:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415094.276751:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00010000:00010000:3.0:1786415094.276753:0:6192:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:3.0:1786415094.276809:0:2411:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_00:596bd973-15df-48a1-9a23-d267a70f4eca:2411:1873191719933184:192.168.204.144@tcp:103:
00000100:00100000:3.0:1786415094.276902:0:2411:0:(ptlrpcd.c:344:ptlrpcd_check()) transfer 1 async RPCs [1->0]
00000100:00100000:3.0:1786415094.276909:0:2411:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b54700 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_00:596bd973-15df-48a1-9a23-d267a70f4eca:2411:1873191719933312:192.168.204.144@tcp:103:
00010000:00010000:2.0:1786415094.277197:0:6193:0:(ldlm_lockd.c:2804:ldlm_bl_get_work()) No blwi found in queue (no bl locks in queue)
00000100:00100000:0.0:1786415094.277552:0:6183:0:(service.c:2610:ptlrpc_server_handle_request()) Handled RPC req@ffff9a56090e4000 pname:cluuid+ref:pid:xid:nid:opc:job ldlm_cb00_001:596bd973-15df-48a1-9a23-d267a70f4eca+3:10350:x1873191745160320:12345-192.168.204.144@tcp:104: Request processed in 1390us (1731us total) trans 0 rc 0/0
00000100:00100000:0.0:1786415094.277561:0:6183:0:(nrs_fifo.c:211:nrs_fifo_req_stop()) NRS stop fifo request from 12345-192.168.204.144@tcp, seq: 1
00000100:00100000:3.0:1786415094.303504:0:2411:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b54700 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_00:596bd973-15df-48a1-9a23-d267a70f4eca:2411:1873191719933312:192.168.204.144@tcp:103:
00000100:00100000:3.0:1786415094.303526:0:2411:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ptlrpcd_01_00:596bd973-15df-48a1-9a23-d267a70f4eca:2411:1873191719933184:192.168.204.144@tcp:103:
10000000:01000000:3.0:1786415094.695730:0:6195:0:(mgc_request.c:611:do_requeue()) updating log lustre-client
10000000:01000000:3.0:1786415094.695742:0:6195:0:(mgc_request.c:1801:mgc_process_log()) Process log lustre-client-ffff9a560b251800 from 48
10000000:01000000:3.0:1786415094.695749:0:6195:0:(mgc_request.c:996:mgc_enqueue()) Enqueue for lustre-client (res 0x65727473756c)
00010000:00010000:3.0:1786415094.695813:0:6195:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x10000000000000 nid: local remote: 0x0 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.695840:0:6195:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000000000000 ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x0 nid: local remote: 0x0 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.695854:0:6195:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x0 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:3.0:1786415094.695908:0:6195:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719933440:192.168.204.144@tcp:101:
00000100:00100000:3.0:1786415094.696547:0:6195:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5606a20240 going to sleep for 11 seconds
00000100:00100000:3.0:1786415094.703729:0:6195:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719933440:192.168.204.144@tcp:101:
00010000:00010000:3.0:1786415094.703773:0:6195:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85885 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.703790:0:6195:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x1000000000000 nid: local remote: 0x5eec1eb7faf85885 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:3.0:1786415094.703903:0:6195:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719933568:192.168.204.144@tcp:501:
00000100:00100000:3.0:1786415094.704445:0:6195:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5606a20240 going to sleep for 11 seconds
00000100:00100000:3.0:1786415094.709355:0:6195:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719933568:192.168.204.144@tcp:501:
00000100:00100000:3.0:1786415094.709916:0:6195:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719933696:192.168.204.144@tcp:503:
00000100:00100000:3.0:1786415094.710630:0:6195:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5606a20240 going to sleep for 11 seconds
00000100:00100000:3.0:1786415094.716904:0:6195:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719933696:192.168.204.144@tcp:503:
00000100:00100000:1.0:1786415094.718701:0:6674:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6674:1873191719933824:192.168.204.144@tcp:502:
00000100:00100000:1.0:1786415094.719013:0:6674:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a560a3a79c0 going to sleep for 11 seconds
00000100:00100000:1.0:1786415094.725217:0:6674:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6674:1873191719933824:192.168.204.144@tcp:502:
00000020:01000000:1.0:1786415094.725291:0:6674:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x0 mark_flg=0x1
00000020:00000080:1.0:1786415094.725305:0:6674:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415094.725307:0:6674:0:(obd_config.c:1561:class_process_config()) marker 42 (0x1) lustre sys.jobid_var
00000020:00000080:1.0:1786415094.725316:0:6674:0:(obd_config.c:1470:class_process_config()) processing cmd: cf00f
00000100:00100000:1.0:1786415094.725371:0:6674:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6674:1873191719933952:192.168.204.144@tcp:502:llog_process_th.0
00000100:00100000:1.0:1786415094.725866:0:6674:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a560a3a79c0 going to sleep for 11 seconds
00000100:00100000:1.0:1786415094.733099:0:6674:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609e3a300 pname:cluuid:pid:xid:nid:opc:job llog_process_th:596bd973-15df-48a1-9a23-d267a70f4eca:6674:1873191719933952:192.168.204.144@tcp:502:llog_process_th.0
00000020:01000000:1.0:1786415094.733166:0:6674:0:(obd_config.c:1890:class_config_llog_handler()) Marker, inst_flg=0x2 mark_flg=0x2
00000020:00000080:1.0:1786415094.733180:0:6674:0:(obd_config.c:1470:class_process_config()) processing cmd: cf010
00000020:00000080:1.0:1786415094.733183:0:6674:0:(obd_config.c:1561:class_process_config()) marker 42 (0x2) lustre sys.jobid_var
00000040:00080000:1.0:1786415094.733189:0:6674:0:(llog.c:862:llog_process_thread()) stop processing plain [0xa:0x3:0x0] index 52 count 51
00000020:01000000:3.0:1786415094.733441:0:6195:0:(obd_config.c:2145:class_config_parse_llog()) Processed log lustre-client gen 48-51 (rc=0)
10000000:01000000:3.0:1786415094.733465:0:6195:0:(mgc_request.c:1878:mgc_process_log()) MGC192.168.204.144@tcp: configuration from log 'lustre-client' succeeded (0).
00010000:00010000:3.0:1786415094.733478:0:6195:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85885 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.733491:0:6195:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: MGC192.168.204.144@tcp lock: ffff9a5646275800/0x85418d5ef8bb35a9 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 2 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf85885 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
10000000:01000000:3.0:1786415094.733504:0:6195:0:(mgc_request.c:611:do_requeue()) updating log lustre-sptlrpc
10000000:01000000:3.0:1786415094.733514:0:6195:0:(mgc_request.c:1801:mgc_process_log()) Process log lustre-sptlrpc-ffff9a56063a99c0 from 1
10000000:01000000:3.0:1786415094.733517:0:6195:0:(mgc_request.c:996:mgc_enqueue()) Enqueue for lustre-sptlrpc (res 0x65727473756c)
00010000:00010000:3.0:1786415094.733602:0:6195:0:(ldlm_lock.c:775:ldlm_lock_addref_internal_nolock()) ### ldlm_lock_addref(CR) ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x10000000000000 nid: local remote: 0x0 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.733613:0:6195:0:(ldlm_request.c:1107:ldlm_cli_enqueue()) ### client-side enqueue START, flags 0x1000000000000 ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x0 nid: local remote: 0x0 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.733625:0:6195:0:(ldlm_request.c:1194:ldlm_cli_enqueue()) ### sending request ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 3/1,0 mode: --/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1000000000000 nid: local remote: 0x0 expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:3.0:1786415094.733653:0:6195:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719934080:192.168.204.144@tcp:101:ll_cfg_requeue.0
00000100:00100000:3.0:1786415094.733775:0:6195:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5606a20240 going to sleep for 11 seconds
00000100:00100000:3.0:1786415094.747374:0:6195:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719934080:192.168.204.144@tcp:101:ll_cfg_requeue.0
00010000:00010000:3.0:1786415094.747413:0:6195:0:(ldlm_lock.c:1072:ldlm_granted_list_add_lock()) ### About to add lock: ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8588c expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.747428:0:6195:0:(ldlm_request.c:841:ldlm_cli_enqueue_fini()) ### client-side enqueue END ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 4/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x1000000000000 nid: local remote: 0x5eec1eb7faf8588c expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00000100:00100000:3.0:1786415094.747535:0:6195:0:(client.c:1906:ptlrpc_send_new_req()) Sending RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719934208:192.168.204.144@tcp:501:ll_cfg_requeue.0
00000100:00100000:3.0:1786415094.747720:0:6195:0:(client.c:2702:ptlrpc_set_wait()) set ffff9a5606a20240 going to sleep for 11 seconds
00000100:00100000:3.0:1786415094.754686:0:6195:0:(client.c:2401:ptlrpc_check_set()) Completed RPC req@ffff9a5609b55f80 pname:cluuid:pid:xid:nid:opc:job ll_cfg_requeue:596bd973-15df-48a1-9a23-d267a70f4eca:6195:1873191719934208:192.168.204.144@tcp:501:ll_cfg_requeue.0
10000000:01000000:3.0:1786415094.754733:0:6195:0:(mgc_request.c:1878:mgc_process_log()) MGC192.168.204.144@tcp: configuration from log 'lustre-sptlrpc' failed (-2).
00010000:00010000:3.0:1786415094.754744:0:6195:0:(ldlm_lock.c:829:ldlm_lock_decref_internal_nolock()) ### ldlm_lock_decref(CR) ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 3/1,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8588c expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
00010000:00010000:3.0:1786415094.754754:0:6195:0:(ldlm_lock.c:919:ldlm_lock_decref_internal()) ### do not add lock into lru list ns: MGC192.168.204.144@tcp lock: ffff9a5646275c00/0x85418d5ef8bb35b0 lrc: 2/0,0 mode: CR/CR res: [0x65727473756c:0x0:0x0].0x0 rrc: 3 type: PLN flags: 0x11000000000000 nid: local remote: 0x5eec1eb7faf8588c expref: -99 pid: 6195 timeout: 0 lvb_type: 0 lru_score: 0 lru_type: 0
Debug log: 937 lines, 937 kept, 0 dropped, 0 bad.