How to use

stopTrace

method

in

org.sonar.core.util.logs.Profiler

private void doUpgradeDb() {
 Profiler profiler = Profiler.createIfTrace(LOGGER);
 profiler.startTrace("Starting DB Migration");
 migrationEngine.execute();
 profiler.stopTrace("DB Migration ended");
}

private void doRestartContainer() {
 Profiler profiler = Profiler.createIfTrace(LOGGER);
 profiler.startTrace("Restarting container");
 platform.doStart();
 profiler.stopTrace("Container restarted successfully");
}

@Test
public void stopTrace_adds_context_after_time_by_default() {
 tester.setLevel(LoggerLevel.TRACE);
 addSomeContext(underTest);
 underTest.start().stopTrace("Rules registered");
 assertThat(tester.logs()).hasSize(1);
 assertThat(tester.logs(LoggerLevel.TRACE).get(0))
  .startsWith("Rules registered | time=")
  .endsWith("ms | a_string=bar | an_int=42 | after_start=true");
}

@Test
public void stopTrace_clears_context() {
 tester.setLevel(LoggerLevel.TRACE);
 addSomeContext(underTest);
 underTest.logTimeLast(true);
 underTest.start().stopTrace("Foo");
 underTest.start().stopTrace("Bar");
 assertThat(tester.logs()).hasSize(2);
 List<String> logs = tester.logs(LoggerLevel.TRACE);
 assertThat(logs.get(0))
   .startsWith("Foo | a_string=bar | an_int=42 | after_start=true | time=")
   .endsWith("ms");
 assertThat(logs.get(1))
   .startsWith("Bar | time=")
   .endsWith("ms");
}

@Test
public void stopTrace_adds_context_before_time_if_logTimeLast_is_true() {
 tester.setLevel(LoggerLevel.TRACE);
 addSomeContext(underTest);
 underTest.logTimeLast(true);
 underTest.start().stopTrace("Rules registered");
 assertThat(tester.logs()).hasSize(1);
 assertThat(tester.logs(LoggerLevel.TRACE).get(0))
  .startsWith("Rules registered | a_string=bar | an_int=42 | after_start=true | time=")
  .endsWith("ms");
}

p.stopTrace("loaded");
p.stopTrace("Score matrix computed");
scoreMatrixDumper.dumpAsCsv(scoreMatrix);
p.stopTrace("Matches elected");

@Test
@UseDataProvider("logTimeLastValues")
public void different_start_and_stop_messages(boolean logTimeLast) {
 underTest.logTimeLast(logTimeLast);
 tester.setLevel(LoggerLevel.TRACE);
 // start TRACE and stop DEBUG
 underTest.startTrace("Register rules");
 underTest.stopDebug("Rules registered");
 assertThat(tester.logs()).hasSize(2);
 assertThat(tester.logs().get(0)).contains("Register rules");
 assertThat(tester.logs().get(1)).startsWith("Rules registered | time=");
 tester.clear();
 // start DEBUG and stop INFO
 underTest.startDebug("Register rules {}", 10);
 underTest.stopInfo("Rules registered");
 assertThat(tester.logs()).hasSize(2);
 assertThat(tester.logs().get(0)).contains("Register rules 10");
 assertThat(tester.logs().get(1)).startsWith("Rules registered | time=");
 tester.clear();
 // start INFO and stop TRACE
 underTest.startInfo("Register rules");
 underTest.stopTrace("Rules registered");
 assertThat(tester.logs()).hasSize(2);
 assertThat(tester.logs().get(0)).contains("Register rules");
 assertThat(tester.logs().get(1)).startsWith("Rules registered | time=");
}

Thread.sleep(2);
assertThat(tester.logs()).containsOnly("Register rules 1");
long timing = underTest.stopTrace();
assertThat(timing).isGreaterThan(0);
assertThat(tester.logs()).hasSize(2);
Thread.sleep(2);
assertThat(tester.logs()).containsOnly("Register rules");
timing = underTest.stopTrace();
assertThat(timing).isGreaterThan(0);
assertThat(tester.logs()).hasSize(2);
Thread.sleep(2);
assertThat(tester.logs()).containsOnly("Register rules");
timing = underTest.stopTrace();
assertThat(timing).isGreaterThan(0);
assertThat(tester.logs()).hasSize(2);

private void visitNode(Component component, VisitorWrapper visitor) {
 Profiler profiler = Profiler.create(Loggers.get(visitor.getWrappedVisitor().getClass()))
  .startTrace("Visiting component {}", component.getDbKey());
 visitor.visitAny(component);
 switch (component.getType()) {
  case PROJECT:
   visitor.visitProject(component);
   break;
  case DIRECTORY:
   visitor.visitDirectory(component);
   break;
  case FILE:
   visitor.visitFile(component);
   break;
  case VIEW:
   visitor.visitView(component);
   break;
  case SUBVIEW:
   visitor.visitSubView(component);
   break;
  case PROJECT_VIEW:
   visitor.visitProjectView(component);
   break;
  default:
   throw new IllegalStateException(String.format("Unknown type %s", component.getType().name()));
 }
 long duration = profiler.stopTrace();
 incrementDuration(visitor, duration);
}

@Test
@UseDataProvider("logTimeLastValues")
public void log_on_at_stop(boolean logTimeLast) {
 underTest.logTimeLast(logTimeLast);
 tester.setLevel(LoggerLevel.TRACE);
 // trace
 underTest.start();
 underTest.stopTrace("Rules registered");
 assertThat(tester.logs()).hasSize(1);
 assertThat(tester.logs().get(0)).startsWith("Rules registered | time=");
 tester.clear();
 // debug
 underTest.start();
 underTest.stopDebug("Rules registered {} on {}", 6, 10);
 assertThat(tester.logs()).hasSize(1);
 assertThat(tester.logs().get(0)).startsWith("Rules registered 6 on 10 | time=");
 tester.clear();
 // info
 underTest.start();
 underTest.stopInfo("Rules registered");
 assertThat(tester.logs()).hasSize(1);
 assertThat(tester.logs().get(0)).startsWith("Rules registered | time=");
}

private void doRestartContainer() {
 Profiler profiler = Profiler.createIfTrace(LOGGER);
 profiler.startTrace("Restarting container");
 platform.doStart();
 profiler.stopTrace("Container restarted successfully");
}

private void doUpgradeDb() {
 Profiler profiler = Profiler.createIfTrace(LOGGER);
 profiler.startTrace("Starting DB Migration");
 migrationEngine.execute();
 profiler.stopTrace("DB Migration ended");
}

p.stopTrace("loaded");
p.stopTrace("Score matrix computed");
scoreMatrixDumper.dumpAsCsv(scoreMatrix);
p.stopTrace("Matches elected");

  throw new IllegalStateException(String.format("Unknown type %s", component.getType().name()));
long duration = profiler.stopTrace();
incrementDuration(visitor, duration);