From: Xinze Chi <xmdxcxz@gmail.com>
Date: Tue, 7 Apr 2015 11:24:27 +0000 (+0800)
Subject: osd: dout latency info in filestore
X-Git-Tag: v9.0.3~135^2~1
X-Git-Url: http://git-server-git.apps.pok.os.sepia.ceph.com/?a=commitdiff_plain;h=4fed20073ead503c7494d25b05e6e346bd853636;p=ceph.git

osd: dout latency info in filestore

dout more latency info for each seq in filestore, this is useful for
tracking io request.

Signed-off-by: Xinze Chi <xmdxcxz@gmail.com>
---

diff --git a/src/os/FileStore.cc b/src/os/FileStore.cc
index 1e6ac23dbd69..36c1cbed4f6d 100644
--- a/src/os/FileStore.cc
+++ b/src/os/FileStore.cc
@@ -1810,14 +1810,15 @@ void FileStore::_finish_op(OpSequencer *osr)
   list<Context*> to_queue;
   Op *o = osr->dequeue(&to_queue);
   
-  dout(10) << "_finish_op " << o << " seq " << o->op << " " << *osr << "/" << osr->parent << dendl;
+  utime_t lat = ceph_clock_now(g_ceph_context);
+  lat -= o->start;
+
+  dout(10) << "_finish_op " << o << " seq " << o->op << " " << *osr << "/" << osr->parent << " lat " << lat << dendl;
   osr->apply_lock.Unlock();  // locked in _do_op
 
   // called with tp lock held
   op_queue_release_throttle(o);
 
-  utime_t lat = ceph_clock_now(g_ceph_context);
-  lat -= o->start;
   logger->tinc(l_os_apply_lat, lat);
 
   if (o->onreadable_sync) {
diff --git a/src/os/KeyValueStore.cc b/src/os/KeyValueStore.cc
index a1c02608af7b..919dcee8697c 100644
--- a/src/os/KeyValueStore.cc
+++ b/src/os/KeyValueStore.cc
@@ -1127,12 +1127,13 @@ void KeyValueStore::_finish_op(OpSequencer *osr)
   list<Context*> to_queue;
   Op *o = osr->dequeue(&to_queue);
 
-  dout(10) << "_finish_op " << o << " seq " << o->op << " " << *osr << "/" << osr->parent << dendl;
+  utime_t lat = ceph_clock_now(g_ceph_context);
+  lat -= o->start;
+
+  dout(10) << "_finish_op " << o << " seq " << o->op << " " << *osr << "/" << osr->parent << " lat " << lat << dendl;
   osr->apply_lock.Unlock();  // locked in _do_op
   op_queue_release_throttle(o);
 
-  utime_t lat = ceph_clock_now(g_ceph_context);
-  lat -= o->start;
   perf_logger->tinc(l_os_commit_lat, lat);
   perf_logger->tinc(l_os_apply_lat, lat);