2021-10-08 21:38:18 +00:00
|
|
|
defmodule OpentelemetryEcto do
|
|
|
|
@moduledoc """
|
2022-03-24 20:19:24 +00:00
|
|
|
Telemetry handler for creating OpenTelemetry Spans from Ecto query events. Any
|
|
|
|
relation preloads, which are executed in parallel in separate tasks, will be
|
|
|
|
linked to the span of the process that initiated the call. For example:
|
|
|
|
|
|
|
|
Tracer.with_span "parent span" do
|
|
|
|
Repo.all(Query.from(User, preload: [:posts, :comments]))
|
|
|
|
end
|
|
|
|
|
|
|
|
this will create a span called "parent span" with three child spans for each
|
|
|
|
query: users, posts, and comments.
|
|
|
|
|
|
|
|
> #### Note {: .neutral}
|
|
|
|
>
|
|
|
|
> Due to limitations with how Ecto emits its telemetry, nested preloads are not
|
|
|
|
> represented as nested spans within a trace.
|
2021-10-08 21:38:18 +00:00
|
|
|
"""
|
|
|
|
|
|
|
|
require OpenTelemetry.Tracer
|
|
|
|
|
|
|
|
@doc """
|
|
|
|
Attaches the OpentelemetryEcto handler to your repo events. This should be called
|
|
|
|
from your application behaviour on startup.
|
|
|
|
|
|
|
|
Example:
|
|
|
|
|
|
|
|
OpentelemetryEcto.setup([:blog, :repo])
|
|
|
|
|
|
|
|
You may also supply the following options in the second argument:
|
|
|
|
|
|
|
|
* `:time_unit` - a time unit used to convert the values of query phase
|
|
|
|
timings, defaults to `:microsecond`. See `System.convert_time_unit/3`
|
|
|
|
|
|
|
|
* `:span_prefix` - the first part of the span name, as a `String.t`,
|
|
|
|
defaults to the concatenation of the event name with periods, e.g.
|
|
|
|
`"blog.repo.query"`. This will always be followed with a colon and the
|
|
|
|
source (the table name for SQL adapters).
|
2022-09-02 19:47:34 +00:00
|
|
|
* `:additional_attributes` - additional attributes to include in the span. If there
|
|
|
|
are conflits with default provided attributes, the ones provided with
|
|
|
|
this config will have precedence.
|
2021-10-08 21:38:18 +00:00
|
|
|
"""
|
|
|
|
def setup(event_prefix, config \\ []) do
|
|
|
|
event = event_prefix ++ [:query]
|
|
|
|
:telemetry.attach({__MODULE__, event}, event, &__MODULE__.handle_event/4, config)
|
|
|
|
end
|
|
|
|
|
|
|
|
@doc false
|
|
|
|
def handle_event(
|
|
|
|
event,
|
|
|
|
measurements,
|
|
|
|
%{query: query, source: source, result: query_result, repo: repo, type: type},
|
|
|
|
config
|
|
|
|
) do
|
|
|
|
# Doing all this even if the span isn't sampled so the sampler
|
|
|
|
# could technically use the attributes to decide if it should sample or not
|
|
|
|
|
|
|
|
total_time = measurements.total_time
|
|
|
|
end_time = :opentelemetry.timestamp()
|
|
|
|
start_time = end_time - total_time
|
|
|
|
database = repo.config()[:database]
|
|
|
|
|
|
|
|
url =
|
|
|
|
case repo.config()[:url] do
|
|
|
|
nil ->
|
|
|
|
# TODO: add port
|
|
|
|
URI.to_string(%URI{scheme: "ecto", host: repo.config()[:hostname]})
|
|
|
|
|
|
|
|
url ->
|
|
|
|
url
|
|
|
|
end
|
|
|
|
|
|
|
|
span_name =
|
|
|
|
case Keyword.fetch(config, :span_prefix) do
|
|
|
|
{:ok, prefix} -> prefix
|
|
|
|
:error -> Enum.join(event, ".")
|
2021-10-14 03:16:07 +00:00
|
|
|
end <> if source != nil, do: ":#{source}", else: ""
|
2021-10-08 21:38:18 +00:00
|
|
|
|
|
|
|
time_unit = Keyword.get(config, :time_unit, :microsecond)
|
2022-09-02 19:47:34 +00:00
|
|
|
additional_attributes = Keyword.get(config, :additional_attributes, %{})
|
2021-10-08 21:38:18 +00:00
|
|
|
|
|
|
|
db_type =
|
|
|
|
case type do
|
|
|
|
:ecto_sql_query -> :sql
|
|
|
|
_ -> type
|
|
|
|
end
|
|
|
|
|
|
|
|
# TODO: need connection information to complete the required attributes
|
|
|
|
# net.peer.name or net.peer.ip and net.peer.port
|
2021-12-28 23:39:06 +00:00
|
|
|
base_attributes = %{
|
2021-11-22 19:08:31 +00:00
|
|
|
"db.type": db_type,
|
|
|
|
"db.statement": query,
|
|
|
|
source: source,
|
|
|
|
"db.instance": database,
|
|
|
|
"db.url": url,
|
|
|
|
"total_time_#{time_unit}s": System.convert_time_unit(total_time, :native, time_unit)
|
2021-12-28 23:39:06 +00:00
|
|
|
}
|
2021-10-08 21:38:18 +00:00
|
|
|
|
|
|
|
attributes =
|
|
|
|
measurements
|
2021-12-28 23:39:06 +00:00
|
|
|
|> Enum.reduce(%{}, fn
|
2022-03-14 16:22:22 +00:00
|
|
|
{k, v}, acc when not is_nil(v) and k in [:decode_time, :query_time, :queue_time, :idle_time] ->
|
2021-12-28 23:39:06 +00:00
|
|
|
Map.put(acc, String.to_atom("#{k}_#{time_unit}s"), System.convert_time_unit(v, :native, time_unit))
|
|
|
|
|
|
|
|
_, acc ->
|
|
|
|
acc
|
2021-10-08 21:38:18 +00:00
|
|
|
end)
|
2022-09-02 19:47:34 +00:00
|
|
|
|> Map.merge(base_attributes)
|
|
|
|
|> Map.merge(additional_attributes)
|
2021-10-08 21:38:18 +00:00
|
|
|
|
2022-03-24 20:19:24 +00:00
|
|
|
parent_context = OpentelemetryProcessPropagator.fetch_parent_ctx(1, :"$callers")
|
|
|
|
|
|
|
|
if parent_context != :undefined do
|
|
|
|
OpenTelemetry.Ctx.attach(parent_context)
|
|
|
|
end
|
|
|
|
|
2021-10-08 21:38:18 +00:00
|
|
|
s =
|
|
|
|
OpenTelemetry.Tracer.start_span(span_name, %{
|
|
|
|
start_time: start_time,
|
2022-09-02 19:47:34 +00:00
|
|
|
attributes: attributes,
|
2021-11-22 18:49:58 +00:00
|
|
|
kind: :client
|
2021-10-08 21:38:18 +00:00
|
|
|
})
|
|
|
|
|
2021-11-22 19:08:31 +00:00
|
|
|
case query_result do
|
|
|
|
{:error, error} ->
|
|
|
|
OpenTelemetry.Span.set_status(s, OpenTelemetry.status(:error, format_error(error)))
|
|
|
|
|
|
|
|
{:ok, _} ->
|
|
|
|
:ok
|
|
|
|
end
|
|
|
|
|
2021-10-08 21:38:18 +00:00
|
|
|
OpenTelemetry.Span.end_span(s)
|
2022-03-24 20:19:24 +00:00
|
|
|
|
|
|
|
if parent_context != :undefined do
|
|
|
|
OpenTelemetry.Ctx.detach(parent_context)
|
|
|
|
end
|
2021-10-08 21:38:18 +00:00
|
|
|
end
|
2021-11-22 19:08:31 +00:00
|
|
|
|
|
|
|
defp format_error(%{__exception__: true} = exception) do
|
|
|
|
Exception.message(exception)
|
|
|
|
end
|
|
|
|
|
|
|
|
defp format_error(_), do: ""
|
2021-10-08 21:38:18 +00:00
|
|
|
end
|