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"); }
@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"); }
throw new IllegalStateException(String.format("Unknown type %s", component.getType().name())); long duration = profiler.stopTrace(); incrementDuration(visitor, duration);