svn commit: r1672834 - /jackrabbit/oak/trunk/oak-lucene/src/main/java/org/apache/jackrabbit/oak/plugins/index/lucene/IndexTracker.java

[chetanm]

Author: chetanm
Date: Sat Apr 11 06:11:43 2015
New Revision: 1672834

URL: http://svn.apache.org/r1672834
Log:
OAK-2473 - Log time taken in couple of places in indexing

Log time taken in opening IndexReader for searching

Modified:
    
jackrabbit/oak/trunk/oak-lucene/src/main/java/org/apache/jackrabbit/oak/plugins/index/lucene/IndexTracker.java

Modified: 
jackrabbit/oak/trunk/oak-lucene/src/main/java/org/apache/jackrabbit/oak/plugins/index/lucene/IndexTracker.java
URL: 
http://svn.apache.org/viewvc/jackrabbit/oak/trunk/oak-lucene/src/main/java/org/apache/jackrabbit/oak/plugins/index/lucene/IndexTracker.java?rev=1672834&r1=1672833&r2=1672834&view=diff
==============================================================================
--- 
jackrabbit/oak/trunk/oak-lucene/src/main/java/org/apache/jackrabbit/oak/plugins/index/lucene/IndexTracker.java
 (original)
+++ 
jackrabbit/oak/trunk/oak-lucene/src/main/java/org/apache/jackrabbit/oak/plugins/index/lucene/IndexTracker.java
 Sat Apr 11 06:11:43 2015
@@ -41,6 +41,7 @@ import org.apache.jackrabbit.oak.spi.com
 import org.apache.jackrabbit.oak.spi.commit.EditorDiff;
 import org.apache.jackrabbit.oak.spi.commit.SubtreeEditor;
 import org.apache.jackrabbit.oak.spi.state.NodeState;
+import org.apache.jackrabbit.oak.util.PerfLogger;
 import org.slf4j.Logger;
 import org.slf4j.LoggerFactory;
 
@@ -50,8 +51,9 @@ import com.google.common.collect.Iterabl
 class IndexTracker {
 
     /** Logger instance. */
-    private static final Logger log =
-            LoggerFactory.getLogger(IndexTracker.class);
+    private static final Logger log = 
LoggerFactory.getLogger(IndexTracker.class);
+    private static final PerfLogger PERF_LOGGER =
+            new 
PerfLogger(LoggerFactory.getLogger(IndexTracker.class.getName() + ".perf"));
 
     private final IndexCopier cloner;
 
@@ -92,8 +94,9 @@ class IndexTracker {
                 @Override
                 public void leave(NodeState before, NodeState after) {
                     try {
+                        long start = PERF_LOGGER.start();
                         IndexNode index = IndexNode.open(path, root, after, 
cloner);
-                        log.debug("Index found to be updated at [{}]. 
Reopening the IndexNode", path);
+                        PERF_LOGGER.end(start, -1, "Index found to be updated 
at [{}]. Reopening the IndexNode", path);
                         updates.put(path, index); // index can be null
                     } catch (IOException e) {
                         log.error("Failed to open Lucene index at " + path, e);