diff options
author | Ivan Topolnak <ivantopo@gmail.com> | 2013-11-12 18:15:00 -0300 |
---|---|---|
committer | Ivan Topolnak <ivantopo@gmail.com> | 2013-11-12 18:15:00 -0300 |
commit | ef8ef647162b5f3eb9033bafb87805e2de5f576e (patch) | |
tree | b7c0d86e6519b497903152d23c307652f0e3a2f5 /kamon-trace | |
parent | 5c8eb362ea6a957449419cec6c48b54159d4c68e (diff) | |
download | Kamon-ef8ef647162b5f3eb9033bafb87805e2de5f576e.tar.gz Kamon-ef8ef647162b5f3eb9033bafb87805e2de5f576e.tar.bz2 Kamon-ef8ef647162b5f3eb9033bafb87805e2de5f576e.zip |
Initial ask pattern tracing
Diffstat (limited to 'kamon-trace')
8 files changed, 103 insertions, 8 deletions
diff --git a/kamon-trace/src/main/resources/reference.conf b/kamon-trace/src/main/resources/reference.conf new file mode 100644 index 00000000..03f13f01 --- /dev/null +++ b/kamon-trace/src/main/resources/reference.conf @@ -0,0 +1,3 @@ +kamon.trace { + ask-pattern-tracing = off +}
\ No newline at end of file diff --git a/kamon-trace/src/main/scala/kamon/trace/Trace.scala b/kamon-trace/src/main/scala/kamon/trace/Trace.scala index d5bb1ddf..0ef438d4 100644 --- a/kamon-trace/src/main/scala/kamon/trace/Trace.scala +++ b/kamon-trace/src/main/scala/kamon/trace/Trace.scala @@ -27,10 +27,12 @@ object Trace extends ExtensionId[TraceExtension] with ExtensionIdProvider { def set(ctx: TraceContext) = traceContext.value = Some(ctx) def clear: Unit = traceContext.value = None - def start(name: String)(implicit system: ActorSystem) = { - val ctx = newTraceContext() + def start(name: String)(implicit system: ActorSystem): TraceContext = { + val ctx = newTraceContext(name) ctx.start(name) set(ctx) + + ctx } def transformContext(f: TraceContext => TraceContext): Unit = { @@ -44,7 +46,7 @@ object Trace extends ExtensionId[TraceExtension] with ExtensionIdProvider { } // TODO: FIX - def newTraceContext()(implicit system: ActorSystem): TraceContext = TraceContext(Kamon(Trace), tranid.getAndIncrement) + def newTraceContext(name: String)(implicit system: ActorSystem): TraceContext = TraceContext(Kamon(Trace), tranid.getAndIncrement, name) } class TraceExtension(system: ExtendedActorSystem) extends Kamon.Extension { diff --git a/kamon-trace/src/main/scala/kamon/trace/instrumentation/ActorLoggingInstrumentation.scala b/kamon-trace/src/main/scala/kamon/trace/instrumentation/ActorLoggingInstrumentation.scala index 77993cdd..fecdc7e3 100644 --- a/kamon-trace/src/main/scala/kamon/trace/instrumentation/ActorLoggingInstrumentation.scala +++ b/kamon-trace/src/main/scala/kamon/trace/instrumentation/ActorLoggingInstrumentation.scala @@ -10,9 +10,7 @@ class ActorLoggingInstrumentation { @DeclareMixin("akka.event.Logging.LogEvent+") - def traceContextMixin: ContextAware = new ContextAware { - def traceContext: Option[TraceContext] = Trace.context() - } + def traceContextMixin: ContextAware = ContextAware.default @Pointcut("execution(* akka.event.slf4j.Slf4jLogger.withMdc(..)) && args(logSource, logEvent, logStatement)") def withMdcInvocation(logSource: String, logEvent: ContextAware, logStatement: () => _): Unit = {} diff --git a/kamon-trace/src/main/scala/kamon/trace/instrumentation/AskPatternTracing.scala b/kamon-trace/src/main/scala/kamon/trace/instrumentation/AskPatternTracing.scala new file mode 100644 index 00000000..625ed1ae --- /dev/null +++ b/kamon-trace/src/main/scala/kamon/trace/instrumentation/AskPatternTracing.scala @@ -0,0 +1,34 @@ +package akka.pattern.tracing + +import org.aspectj.lang.annotation.{After, AfterReturning, Pointcut, Aspect} +import akka.pattern.{AskTimeoutException, PromiseActorRef} +import akka.event.Logging.Warning +import scala.compat.Platform.EOL +import akka.actor.ActorRefProvider + +@Aspect +class AskPatternTracing { + + class StackTraceCaptureException extends Throwable + + @Pointcut(value = "execution(* akka.pattern.PromiseActorRef$.apply(..)) && args(provider, *)", argNames = "provider") + def promiseActorRefApply(provider: ActorRefProvider): Unit = { + provider.settings.config.getBoolean("kamon.trace.ask-pattern-tracing") + } + + @AfterReturning(pointcut = "promiseActorRefApply(provider)", returning = "promiseActor") + def hookAskTimeoutWarning(provider: ActorRefProvider, promiseActor: PromiseActorRef): Unit = { + val future = promiseActor.result.future + val system = promiseActor.provider.guardian.underlying.system + implicit val ec = system.dispatcher + val stack = new StackTraceCaptureException + + future onFailure { + case timeout: AskTimeoutException => + val stackString = stack.getStackTrace.drop(3).mkString("", EOL, EOL) + + system.eventStream.publish(Warning("AskPatternTracing", classOf[AskPatternTracing], + "Timeout triggered for ask pattern registered at: " + stackString)) + } + } +} diff --git a/kamon-trace/src/test/resources/application.conf b/kamon-trace/src/test/resources/application.conf new file mode 100644 index 00000000..e8217fc2 --- /dev/null +++ b/kamon-trace/src/test/resources/application.conf @@ -0,0 +1,3 @@ +akka { + loggers = ["akka.event.slf4j.Slf4jLogger"] +}
\ No newline at end of file diff --git a/kamon-trace/src/test/resources/logback.xml b/kamon-trace/src/test/resources/logback.xml new file mode 100644 index 00000000..2ae1e3bd --- /dev/null +++ b/kamon-trace/src/test/resources/logback.xml @@ -0,0 +1,12 @@ +<configuration scan="true"> + <appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender"> + <encoder> + <pattern>%date{HH:mm:ss.SSS} %-5level [%X{uow}][%X{requestId}] [%thread] %logger{55} - %msg%n</pattern> + </encoder> + </appender> + + <root level="debug"> + <appender-ref ref="STDOUT" /> + </root> + +</configuration> diff --git a/kamon-trace/src/test/scala/kamon/ActorInstrumentationSpec.scala b/kamon-trace/src/test/scala/kamon/ActorInstrumentationSpec.scala index f5d88f06..d675c4f4 100644 --- a/kamon-trace/src/test/scala/kamon/ActorInstrumentationSpec.scala +++ b/kamon-trace/src/test/scala/kamon/ActorInstrumentationSpec.scala @@ -65,7 +65,7 @@ class ActorInstrumentationSpec extends TestKit(ActorSystem("ActorInstrumentation } trait TraceContextEchoFixture { - val testTraceContext = Trace.newTraceContext() + val testTraceContext = Trace.newTraceContext("") val echo = system.actorOf(Props[TraceContextEcho]) Trace.set(testTraceContext) @@ -75,7 +75,7 @@ class ActorInstrumentationSpec extends TestKit(ActorSystem("ActorInstrumentation override val echo = system.actorOf(Props[TraceContextEcho].withRouter(RoundRobinRouter(nrOfInstances = 10))) def tellWithNewContext(target: ActorRef, message: Any): TraceContext = { - val context = Trace.newTraceContext() + val context = Trace.newTraceContext("") Trace.set(context) target ! message diff --git a/kamon-trace/src/test/scala/kamon/AskPatternTracingSpec.scala b/kamon-trace/src/test/scala/kamon/AskPatternTracingSpec.scala new file mode 100644 index 00000000..c2566725 --- /dev/null +++ b/kamon-trace/src/test/scala/kamon/AskPatternTracingSpec.scala @@ -0,0 +1,43 @@ +package kamon + +import akka.testkit.TestKit +import akka.actor.{Props, Actor, ActorSystem} +import org.scalatest.{Matchers, WordSpecLike} +import akka.event.Logging.Warning +import scala.concurrent.duration._ +import akka.pattern.ask +import akka.util.Timeout +import kamon.trace.{Trace, ContextAware} +import org.scalatest.OptionValues._ + + +class AskPatternTracingSpec extends TestKit(ActorSystem("ask-pattern-tracing-spec")) with WordSpecLike with Matchers { + + "the AskPatternTracing" should { + "log a warning with a stack trace and TraceContext taken from the moment the ask was triggered" in { + implicit val ec = system.dispatcher + implicit val timeout = Timeout(10 milliseconds) + val noReply = system.actorOf(Props[NoReply]) + system.eventStream.subscribe(testActor, classOf[Warning]) + + within(500 milliseconds) { + val initialCtx = Trace.start("ask-test") + noReply ? "hello" + + val warn = expectMsgPF() { + case warn: Warning if warn.message.toString.contains("Timeout triggered for ask pattern") => warn + } + val capturedCtx = warn.asInstanceOf[ContextAware].traceContext + + capturedCtx should be('defined) + capturedCtx.value should equal (initialCtx) + } + } + } +} + +class NoReply extends Actor { + def receive = { + case any => + } +} |