async-graphql/src/extensions/tracing.rs
2020-03-26 11:34:28 +08:00

130 lines
4.1 KiB
Rust

use crate::extensions::{Extension, ResolveInfo};
use crate::QueryPath;
use chrono::{DateTime, Utc};
use fnv::FnvHasher;
use parking_lot::Mutex;
use serde::ser::SerializeMap;
use serde::{Serialize, Serializer};
use std::collections::HashMap;
use std::hash::BuildHasherDefault;
struct PendingResolve {
path: QueryPath,
parent_type: String,
return_type: String,
start_time: DateTime<Utc>,
}
struct ResolveStat {
path: QueryPath,
parent_type: String,
return_type: String,
start_time: DateTime<Utc>,
end_time: DateTime<Utc>,
start_offset: i64,
}
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)?;
map.serialize_entry("fieldName", self.path.field_name())?;
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>,
pending_resolves: HashMap<usize, PendingResolve, BuildHasherDefault<FnvHasher>>,
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
///
/// Apollo Tracing is a GraphQL extension for performance tracing.
/// 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.
/// It’s already supported by `Apollo Engine`, and we’re 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.clone(),
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 {
path: pending_resolve.path,
parent_type: pending_resolve.parent_type,
return_type: pending_resolve.return_type,
start_time: pending_resolve.start_time,
start_offset,
end_time: Utc::now(),
});
}
}
fn result(&self) -> serde_json::Value {
let mut inner = self.inner.lock();
inner
.resolves
.sort_by(|a, b| a.start_offset.cmp(&b.start_offset));
serde_json::json!({
"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
}
})
}
}