async-graphql/src/extensions/tracing.rs

131 lines
4.0 KiB
Rust
Raw Normal View History

2020-03-26 03:34:28 +00:00
use crate::extensions::{Extension, ResolveInfo};
use chrono::{DateTime, Utc};
use parking_lot::Mutex;
use serde::ser::SerializeMap;
use serde::{Serialize, Serializer};
2020-03-31 03:19:18 +00:00
use std::collections::BTreeMap;
2020-03-26 10:30:29 +00:00
use std::ops::Deref;
2020-03-26 03:34:28 +00:00
struct PendingResolve {
2020-03-26 10:30:29 +00:00
path: serde_json::Value,
field_name: String,
2020-03-26 03:34:28 +00:00
parent_type: String,
return_type: String,
start_time: DateTime<Utc>,
}
struct ResolveStat {
2020-03-26 10:30:29 +00:00
pending_resolve: PendingResolve,
2020-03-26 03:34:28 +00:00
end_time: DateTime<Utc>,
start_offset: i64,
}
2020-03-26 10:30:29 +00:00
impl Deref for ResolveStat {
type Target = PendingResolve;
fn deref(&self) -> &Self::Target {
&self.pending_resolve
}
}
2020-03-26 03:34:28 +00:00
impl Serialize for ResolveStat {
fn serialize<S: Serializer>(&self, serializer: S) -> std::result::Result<S::Ok, S::Error> {
let mut map = serializer.serialize_map(None)?;
map.serialize_entry("path", &self.path)?;
2020-03-26 10:30:29 +00:00
map.serialize_entry("fieldName", &self.field_name)?;
2020-03-26 03:34:28 +00:00
map.serialize_entry("parentType", &self.parent_type)?;
map.serialize_entry("returnType", &self.return_type)?;
map.serialize_entry("startOffset", &self.start_offset)?;
map.serialize_entry(
"duration",
&(self.end_time - self.start_time).num_nanoseconds(),
)?;
map.end()
}
}
struct Inner {
start_time: DateTime<Utc>,
end_time: DateTime<Utc>,
2020-03-31 03:19:18 +00:00
pending_resolves: BTreeMap<usize, PendingResolve>,
2020-03-26 03:34:28 +00:00
resolves: Vec<ResolveStat>,
}
impl Default for Inner {
fn default() -> Self {
Self {
start_time: Utc::now(),
end_time: Utc::now(),
pending_resolves: Default::default(),
resolves: Default::default(),
}
}
}
/// Apollo tracing extension for performance tracing
2020-03-26 03:34:28 +00:00
///
/// Apollo Tracing works by including data in the extensions field of the GraphQL response, which is reserved by the GraphQL spec for extra information that a server wants to return. That way, you have access to performance traces alongside the data returned by your query.
/// Its already supported by `Apollo Engine`, and were excited to see what other kinds of integrations people can build on top of this format.
#[derive(Default)]
pub struct ApolloTracing {
inner: Mutex<Inner>,
}
impl Extension for ApolloTracing {
fn name(&self) -> &'static str {
"tracing"
}
fn parse_start(&self, _query_source: &str) {
self.inner.lock().start_time = Utc::now();
}
fn execution_end(&self) {
self.inner.lock().end_time = Utc::now();
}
fn resolve_field_start(&self, info: &ResolveInfo<'_>) {
let mut inner = self.inner.lock();
inner.pending_resolves.insert(
info.resolve_id,
PendingResolve {
path: info.path_node.to_json(),
2020-03-26 10:30:29 +00:00
field_name: info.path_node.field_name().to_string(),
2020-03-26 03:34:28 +00:00
parent_type: info.parent_type.to_string(),
return_type: info.return_type.to_string(),
start_time: Utc::now(),
},
);
}
fn resolve_field_end(&self, resolve_id: usize) {
let mut inner = self.inner.lock();
if let Some(pending_resolve) = inner.pending_resolves.remove(&resolve_id) {
let start_offset = (pending_resolve.start_time - inner.start_time)
.num_nanoseconds()
.unwrap();
inner.resolves.push(ResolveStat {
2020-03-26 10:30:29 +00:00
pending_resolve,
2020-03-26 03:34:28 +00:00
start_offset,
end_time: Utc::now(),
});
}
}
fn result(&self) -> Option<serde_json::Value> {
2020-03-26 03:34:28 +00:00
let mut inner = self.inner.lock();
inner
.resolves
.sort_by(|a, b| a.start_offset.cmp(&b.start_offset));
Some(serde_json::json!({
2020-03-26 03:34:28 +00:00
"version": 1,
"startTime": inner.start_time.to_rfc3339(),
"endTime": inner.end_time.to_rfc3339(),
"duration": (inner.end_time - inner.start_time).num_nanoseconds(),
"execution": {
"resolvers": inner.resolves
}
}))
2020-03-26 03:34:28 +00:00
}
}