From: Simon Brandhof Date: Thu, 26 Jul 2018 08:30:05 +0000 (+0200) Subject: SONAR-11077 log profiling of CE steps in INFO level X-Git-Tag: 7.5~685 X-Git-Url: https://source.dussan.org/?a=commitdiff_plain;h=2afc1eb0305f0d8639391820f41cae364b8c286e;p=sonarqube.git SONAR-11077 log profiling of CE steps in INFO level --- diff --git a/server/sonar-ce-task/src/main/java/org/sonar/ce/task/step/ComputationStepExecutor.java b/server/sonar-ce-task/src/main/java/org/sonar/ce/task/step/ComputationStepExecutor.java index 3187f37b7cd..9fa8eea74dd 100644 --- a/server/sonar-ce-task/src/main/java/org/sonar/ce/task/step/ComputationStepExecutor.java +++ b/server/sonar-ce-task/src/main/java/org/sonar/ce/task/step/ComputationStepExecutor.java @@ -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()); } } diff --git a/server/sonar-ce-task/src/test/java/org/sonar/ce/task/step/ComputationStepExecutorTest.java b/server/sonar-ce-task/src/test/java/org/sonar/ce/task/step/ComputationStepExecutorTest.java index ca3af2117f3..e8ae8735f05 100644 --- a/server/sonar-ce-task/src/test/java/org/sonar/ce/task/step/ComputationStepExecutorTest.java +++ b/server/sonar-ce-task/src/test/java/org/sonar/ce/task/step/ComputationStepExecutorTest.java @@ -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 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 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 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 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 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(); }