]> source.dussan.org Git - sonarqube.git/commitdiff
SONAR-11077 log profiling of CE steps in INFO level
authorSimon Brandhof <simon.brandhof@sonarsource.com>
Thu, 26 Jul 2018 08:30:05 +0000 (10:30 +0200)
committerSonarTech <sonartech@sonarsource.com>
Thu, 2 Aug 2018 18:21:33 +0000 (20:21 +0200)
server/sonar-ce-task/src/main/java/org/sonar/ce/task/step/ComputationStepExecutor.java
server/sonar-ce-task/src/test/java/org/sonar/ce/task/step/ComputationStepExecutorTest.java

index 3187f37b7cd28343818d0b1806c7ce66d0921625..9fa8eea74dd968d447a8e4af2261bbb057fba64f 100644 (file)
@@ -62,7 +62,7 @@ public final class ComputationStepExecutor {
     for (ComputationStep step : steps.instances()) {
       stepProfiler.start();
       step.execute();
-      stepProfiler.stopDebug(step.getDescription());
+      stepProfiler.stopInfo(step.getDescription());
     }
   }
 
index ca3af2117f341bd3c663af8a69b49b59586fcb8a..e8ae8735f05e2b3aa878e2ba9900a208cb6f2335 100644 (file)
@@ -83,35 +83,17 @@ public class ComputationStepExecutorTest {
   }
 
   @Test
-  public void execute_does_not_log_end_timing_for_each_ComputationStep_called_when_level_is_INFO() {
-    List<String> infoLogs = execute_logs_end_timing_for_each_ComputationStep_called_when_(LoggerLevel.INFO);
-    assertThat(infoLogs).isEmpty();
-  }
-
-  @Test
-  public void execute_logs_end_timing_for_each_ComputationStep_called_when_level_is_DEBUG() {
-    List<String> infoLogs = execute_logs_end_timing_for_each_ComputationStep_called_when_(LoggerLevel.DEBUG);
-    assertThat(infoLogs).hasSize(2);
-    assertThat(infoLogs.get(0)).contains("step1 | time=");
-    assertThat(infoLogs.get(1)).contains("step2 | time=");
-  }
-
-  @Test
-  public void execute_logs_end_timing_for_each_ComputationStep_called_when_level_is_TRACE() {
-    List<String> infoLogs = execute_logs_end_timing_for_each_ComputationStep_called_when_(LoggerLevel.TRACE);
-    assertThat(infoLogs).hasSize(2);
-    assertThat(infoLogs.get(0)).contains("step1 | time=");
-    assertThat(infoLogs.get(1)).contains("step2 | time=");
-  }
-
-  private List<String> execute_logs_end_timing_for_each_ComputationStep_called_when_(LoggerLevel level) {
-    try (ChangeLogLevel executor = new ChangeLogLevel(ComputationStepExecutor.class, level);
-      ChangeLogLevel step1 = new ChangeLogLevel(computationStep1.getClass(), level);
-      ChangeLogLevel step2 = new ChangeLogLevel(computationStep2.getClass(), level)) {
+  public void execute_logs_end_timing_for_each_ComputationStep_in_INFO_level() {
+    try (ChangeLogLevel executor = new ChangeLogLevel(ComputationStepExecutor.class, LoggerLevel.INFO);
+      ChangeLogLevel step1 = new ChangeLogLevel(computationStep1.getClass(), LoggerLevel.INFO);
+      ChangeLogLevel step2 = new ChangeLogLevel(computationStep2.getClass(), LoggerLevel.INFO)) {
       new ComputationStepExecutor(mockComputationSteps(computationStep1, computationStep2))
         .execute();
 
-      return logTester.logs(LoggerLevel.DEBUG);
+      List<String> infoLogs = logTester.logs(LoggerLevel.INFO);
+      assertThat(infoLogs).hasSize(2);
+      assertThat(infoLogs.get(0)).contains("step1 | time=");
+      assertThat(infoLogs.get(1)).contains("step2 | time=");
     }
   }
 
@@ -146,8 +128,8 @@ public class ComputationStepExecutorTest {
   public void execute_does_not_fail_if_listener_throws_Throwable() {
     ComputationStepExecutor.Listener listener = mock(ComputationStepExecutor.Listener.class);
     doThrow(new Error("Facking error thrown by Listener"))
-        .when(listener)
-        .finished(anyBoolean());
+      .when(listener)
+      .finished(anyBoolean());
 
     new ComputationStepExecutor(mockComputationSteps(computationStep1), listener).execute();
   }