debug http://www.tracker.newdream.net/issues/3133

gistfile1.txt

From 504f94f7a54a0bbf6801be6bda2ed6acfd597321 Mon Sep 17 00:00:00 2001
From: Josh Durgin <josh.durgin@inktank.com>
Date: Wed, 12 Sep 2012 09:37:02 -0700
Subject: [PATCH] librbd: debug aio times

---
 src/librbd.cc |    3 +++
 1 files changed, 3 insertions(+), 0 deletions(-)

diff --git a/src/librbd.cc b/src/librbd.cc
index 4d1d68e..e7e0932 100644
--- a/src/librbd.cc
+++ b/src/librbd.cc
@@ -546,7 +546,10 @@ namespace librbd {
     void complete() {
       utime_t elapsed;
       assert(lock.is_locked());
+      ldout(ictx->cct, 20) << "start_time sec = " << start_time.tv.tv_sec
+			  << " nsec = " << start_time.tv.tv_nsec << dendl;
       elapsed = ceph_clock_now(ictx->cct) - start_time;
+      ldout(ictx->cct, 20) << "elapsed = " << elapsed << dendl;
       if (complete_cb) {
 	complete_cb(rbd_comp, complete_arg);
       }
-- 
1.7.2.5