commit a39f8a9f8ce1fc3c2fcc7a75ba3d7ddab52266ce
author Eric Fellheimer <felly@google.com> Tue Jul 28 19:11:23 2015 +0000
committer Lukacs Berki <lberki@google.com> Wed Jul 29 16:00:39 2015 +0000
tree 363406391f6c95520297edea2336930276d37895
parent c3bca87818351cc9c85e47ac59732c9cbf378724

Add some useful logging of "blaze query" timing.

--
MOS_MIGRATED_REVID=99302436

src/main/java/com/google/devtools/build/lib/query2/SkyQueryEnvironment.java

diff --git a/src/main/java/com/google/devtools/build/lib/query2/SkyQueryEnvironment.java b/src/main/java/com/google/devtools/build/lib/query2/SkyQueryEnvironment.java
index cb0f2f6..6fd5512 100644
--- a/src/main/java/com/google/devtools/build/lib/query2/SkyQueryEnvironment.java
+++ b/src/main/java/com/google/devtools/build/lib/query2/SkyQueryEnvironment.java
@@ -39,6 +39,7 @@
 import com.google.devtools.build.lib.packages.Target;
 import com.google.devtools.build.lib.pkgcache.PathPackageLocator;
 import com.google.devtools.build.lib.pkgcache.TargetPatternEvaluator;
+import com.google.devtools.build.lib.profiler.Profiler;
 import com.google.devtools.build.lib.query2.engine.AllRdepsFunction;
 import com.google.devtools.build.lib.query2.engine.QueryEvalResult;
 import com.google.devtools.build.lib.query2.engine.QueryException;
@@ -69,6 +70,7 @@
 import java.util.List;
 import java.util.Map;
 import java.util.Set;
+import java.util.logging.Logger;
 
 import javax.annotation.Nullable;
 
@@ -90,6 +92,8 @@
   private final String parserPrefix;
   private final PathPackageLocator pkgPath;
 
+  private static final Logger LOG = Logger.getLogger(SkyQueryEnvironment.class.getName());
+
   public SkyQueryEnvironment(boolean keepGoing, boolean strictScope, int loadingPhaseThreads,
       Predicate<Label> labelFilter,
       EventHandler eventHandler,
@@ -111,10 +115,15 @@
   }
 
   private void init() throws InterruptedException {
+    long startTime = Profiler.nanoTimeMaybe();
     EvaluationResult<SkyValue> result =
         graphFactory.prepareAndGet(universeScope, loadingPhaseThreads, eventHandler);
     graph = result.getWalkableGraph();
     Collection<SkyValue> values = result.values();
+    long duration = Profiler.nanoTimeMaybe() - startTime;
+    if (duration > 0) {
+      LOG.info("Spent " + (duration / 1000 / 1000) + " ms on evaluation and walkable graph");
+    }
 
     // The universe query may fail if there are errors during its evaluation, e.g. because of
     // cycles in the target graph.