From 3ee6336125d207acc26dbde2bcc7b98811ab8538 Mon Sep 17 00:00:00 2001
From: ahr <ahr@ahr.local>
Date: Sun, 10 Dec 2017 17:52:32 +0100
Subject: [PATCH] log time of query execution

---
 .../main/java/org/lucares/performance/db/PerformanceDb.java  | 5 +++--
 1 file changed, 3 insertions(+), 2 deletions(-)

diff --git a/performanceDb/src/main/java/org/lucares/performance/db/PerformanceDb.java b/performanceDb/src/main/java/org/lucares/performance/db/PerformanceDb.java
index 8c34086..8f1a70c 100644
--- a/performanceDb/src/main/java/org/lucares/performance/db/PerformanceDb.java
+++ b/performanceDb/src/main/java/org/lucares/performance/db/PerformanceDb.java
@@ -150,13 +150,14 @@ public class PerformanceDb implements AutoCloseable {
 	 * @return {@link Result}
 	 */
 	public Result get(final String query, final List<String> groupBy) {
-
+		final long start = System.nanoTime();
 		final List<PdbFile> pdbFiles = tagsToFile.getFilesForQuery(query);
 
 		final Grouping grouping = Grouping.groupBy(pdbFiles, groupBy);
 
 		final Result result = toResult(grouping);
-
+		METRICS_LOGGER.debug("query execution took: " + (System.nanoTime() - start) / 1_000_000.0
+				+ "ms: " + query + " ("+groupBy+"): files found: " + pdbFiles.size());
 		return result;
 	}