From 83fd2343ad2a09a19f17fbfef195250cd2aab98e Mon Sep 17 00:00:00 2001 From: "hongchao.zhang" Date: Thu, 29 Jul 2010 03:02:09 +0400 Subject: [PATCH] b=23352 add debugging information to late lock cancels print out the arrival time of the delayed cancel RPC, so we can determine whether it was waiting in the queue a long time, or whether it arrived late on the network i=nathan.rutman i=hongchao.zhang --- lustre/ldlm/ldlm_lockd.c | 8 +++++--- 1 file changed, 5 insertions(+), 3 deletions(-) diff --git a/lustre/ldlm/ldlm_lockd.c b/lustre/ldlm/ldlm_lockd.c index 2b1af6f..f59a29f 100644 --- a/lustre/ldlm/ldlm_lockd.c +++ b/lustre/ldlm/ldlm_lockd.c @@ -2005,9 +2005,11 @@ static int ldlm_cancel_handler(struct ptlrpc_request *req) if (req->rq_export == NULL) { struct ldlm_request *dlm_req; - CERROR("operation %d from %s with bad export cookie "LPU64"\n", - lustre_msg_get_opc(req->rq_reqmsg), - libcfs_id2str(req->rq_peer), + CERROR("%s from %s arrived at %lu with bad export cookie " + LPU64"\n", + ll_opcode2str(lustre_msg_get_opc(req->rq_reqmsg)), + libcfs_nid2str(req->rq_peer.nid), + req->rq_arrival_time.tv_sec, lustre_msg_get_handle(req->rq_reqmsg)->cookie); if (lustre_msg_get_opc(req->rq_reqmsg) == LDLM_CANCEL) { -- 1.8.3.1