self-profile: Switch to new approach for event_id generation that enables query-invocation-specific event_ids.
This commit is contained in:
parent
2d8d559bbe
commit
a62c040929
9 changed files with 277 additions and 108 deletions
|
@ -1995,9 +1995,9 @@ dependencies = [
|
||||||
|
|
||||||
[[package]]
|
[[package]]
|
||||||
name = "measureme"
|
name = "measureme"
|
||||||
version = "0.5.0"
|
version = "0.6.0"
|
||||||
source = "registry+https://github.com/rust-lang/crates.io-index"
|
source = "registry+https://github.com/rust-lang/crates.io-index"
|
||||||
checksum = "c420bbc064623934620b5ab2dc0cf96451b34163329e82f95e7fa1b7b99a6ac8"
|
checksum = "36dcc09c1a633097649f7d48bde3d8a61d2a43c01ce75525e31fbbc82c0fccf4"
|
||||||
dependencies = [
|
dependencies = [
|
||||||
"byteorder",
|
"byteorder",
|
||||||
"memmap",
|
"memmap",
|
||||||
|
|
|
@ -36,5 +36,6 @@ parking_lot = "0.9"
|
||||||
byteorder = { version = "1.3" }
|
byteorder = { version = "1.3" }
|
||||||
chalk-engine = { version = "0.9.0", default-features=false }
|
chalk-engine = { version = "0.9.0", default-features=false }
|
||||||
smallvec = { version = "1.0", features = ["union", "may_dangle"] }
|
smallvec = { version = "1.0", features = ["union", "may_dangle"] }
|
||||||
|
measureme = "0.6.0"
|
||||||
rustc_error_codes = { path = "../librustc_error_codes" }
|
rustc_error_codes = { path = "../librustc_error_codes" }
|
||||||
rustc_session = { path = "../librustc_session" }
|
rustc_session = { path = "../librustc_session" }
|
||||||
|
|
|
@ -8,6 +8,8 @@ use rustc_data_structures::sync::{AtomicU32, AtomicU64, Lock, Lrc, Ordering};
|
||||||
use rustc_index::vec::{Idx, IndexVec};
|
use rustc_index::vec::{Idx, IndexVec};
|
||||||
use smallvec::SmallVec;
|
use smallvec::SmallVec;
|
||||||
use std::collections::hash_map::Entry;
|
use std::collections::hash_map::Entry;
|
||||||
|
use rustc_data_structures::profiling::QueryInvocationId;
|
||||||
|
use std::sync::atomic::Ordering::Relaxed;
|
||||||
use std::env;
|
use std::env;
|
||||||
use std::hash::Hash;
|
use std::hash::Hash;
|
||||||
use std::mem;
|
use std::mem;
|
||||||
|
@ -25,6 +27,12 @@ use super::serialized::{SerializedDepGraph, SerializedDepNodeIndex};
|
||||||
#[derive(Clone)]
|
#[derive(Clone)]
|
||||||
pub struct DepGraph {
|
pub struct DepGraph {
|
||||||
data: Option<Lrc<DepGraphData>>,
|
data: Option<Lrc<DepGraphData>>,
|
||||||
|
|
||||||
|
/// This field is used for assigning DepNodeIndices when running in
|
||||||
|
/// non-incremental mode. Even in non-incremental mode we make sure that
|
||||||
|
/// each task as a `DepNodeIndex` that uniquely identifies it. This unique
|
||||||
|
/// ID is used for self-profiling.
|
||||||
|
virtual_dep_node_index: Lrc<AtomicU32>,
|
||||||
}
|
}
|
||||||
|
|
||||||
rustc_index::newtype_index! {
|
rustc_index::newtype_index! {
|
||||||
|
@ -35,6 +43,13 @@ impl DepNodeIndex {
|
||||||
pub const INVALID: DepNodeIndex = DepNodeIndex::MAX;
|
pub const INVALID: DepNodeIndex = DepNodeIndex::MAX;
|
||||||
}
|
}
|
||||||
|
|
||||||
|
impl std::convert::From<DepNodeIndex> for QueryInvocationId {
|
||||||
|
#[inline]
|
||||||
|
fn from(dep_node_index: DepNodeIndex) -> Self {
|
||||||
|
QueryInvocationId(dep_node_index.as_u32())
|
||||||
|
}
|
||||||
|
}
|
||||||
|
|
||||||
#[derive(PartialEq)]
|
#[derive(PartialEq)]
|
||||||
pub enum DepNodeColor {
|
pub enum DepNodeColor {
|
||||||
Red,
|
Red,
|
||||||
|
@ -105,11 +120,15 @@ impl DepGraph {
|
||||||
previous: prev_graph,
|
previous: prev_graph,
|
||||||
colors: DepNodeColorMap::new(prev_graph_node_count),
|
colors: DepNodeColorMap::new(prev_graph_node_count),
|
||||||
})),
|
})),
|
||||||
|
virtual_dep_node_index: Lrc::new(AtomicU32::new(0)),
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
pub fn new_disabled() -> DepGraph {
|
pub fn new_disabled() -> DepGraph {
|
||||||
DepGraph { data: None }
|
DepGraph {
|
||||||
|
data: None,
|
||||||
|
virtual_dep_node_index: Lrc::new(AtomicU32::new(0)),
|
||||||
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
/// Returns `true` if we are actually building the full dep-graph, and `false` otherwise.
|
/// Returns `true` if we are actually building the full dep-graph, and `false` otherwise.
|
||||||
|
@ -322,7 +341,7 @@ impl DepGraph {
|
||||||
|
|
||||||
(result, dep_node_index)
|
(result, dep_node_index)
|
||||||
} else {
|
} else {
|
||||||
(task(cx, arg), DepNodeIndex::INVALID)
|
(task(cx, arg), self.next_virtual_depnode_index())
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -352,7 +371,7 @@ impl DepGraph {
|
||||||
let dep_node_index = data.current.complete_anon_task(dep_kind, task_deps);
|
let dep_node_index = data.current.complete_anon_task(dep_kind, task_deps);
|
||||||
(result, dep_node_index)
|
(result, dep_node_index)
|
||||||
} else {
|
} else {
|
||||||
(op(), DepNodeIndex::INVALID)
|
(op(), self.next_virtual_depnode_index())
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -877,6 +896,11 @@ impl DepGraph {
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
|
fn next_virtual_depnode_index(&self) -> DepNodeIndex {
|
||||||
|
let index = self.virtual_dep_node_index.fetch_add(1, Relaxed);
|
||||||
|
DepNodeIndex::from_u32(index)
|
||||||
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
/// A "work product" is an intermediate result that we save into the
|
/// A "work product" is an intermediate result that we save into the
|
||||||
|
|
|
@ -2,8 +2,7 @@ use crate::dep_graph::SerializedDepNodeIndex;
|
||||||
use crate::dep_graph::{DepKind, DepNode};
|
use crate::dep_graph::{DepKind, DepNode};
|
||||||
use crate::ty::query::plumbing::CycleError;
|
use crate::ty::query::plumbing::CycleError;
|
||||||
use crate::ty::query::queries;
|
use crate::ty::query::queries;
|
||||||
use crate::ty::query::QueryCache;
|
use crate::ty::query::{Query, QueryCache};
|
||||||
use crate::ty::query::{Query, QueryName};
|
|
||||||
use crate::ty::TyCtxt;
|
use crate::ty::TyCtxt;
|
||||||
use rustc_data_structures::profiling::ProfileCategory;
|
use rustc_data_structures::profiling::ProfileCategory;
|
||||||
use rustc_hir::def_id::{CrateNum, DefId};
|
use rustc_hir::def_id::{CrateNum, DefId};
|
||||||
|
@ -20,7 +19,7 @@ use std::hash::Hash;
|
||||||
// FIXME(eddyb) false positive, the lifetime parameter is used for `Key`/`Value`.
|
// FIXME(eddyb) false positive, the lifetime parameter is used for `Key`/`Value`.
|
||||||
#[allow(unused_lifetimes)]
|
#[allow(unused_lifetimes)]
|
||||||
pub trait QueryConfig<'tcx> {
|
pub trait QueryConfig<'tcx> {
|
||||||
const NAME: QueryName;
|
const NAME: &'static str;
|
||||||
const CATEGORY: ProfileCategory;
|
const CATEGORY: ProfileCategory;
|
||||||
|
|
||||||
type Key: Eq + Hash + Clone + Debug;
|
type Key: Eq + Hash + Clone + Debug;
|
||||||
|
|
|
@ -95,7 +95,7 @@ impl<'a, 'tcx, Q: QueryDescription<'tcx>> JobOwner<'a, 'tcx, Q> {
|
||||||
if let Some((_, value)) =
|
if let Some((_, value)) =
|
||||||
lock.results.raw_entry().from_key_hashed_nocheck(key_hash, key)
|
lock.results.raw_entry().from_key_hashed_nocheck(key_hash, key)
|
||||||
{
|
{
|
||||||
tcx.prof.query_cache_hit(Q::NAME);
|
tcx.prof.query_cache_hit(value.index.into());
|
||||||
let result = (value.value.clone(), value.index);
|
let result = (value.value.clone(), value.index);
|
||||||
#[cfg(debug_assertions)]
|
#[cfg(debug_assertions)]
|
||||||
{
|
{
|
||||||
|
@ -347,7 +347,7 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
|
|
||||||
#[inline(never)]
|
#[inline(never)]
|
||||||
pub(super) fn get_query<Q: QueryDescription<'tcx>>(self, span: Span, key: Q::Key) -> Q::Value {
|
pub(super) fn get_query<Q: QueryDescription<'tcx>>(self, span: Span, key: Q::Key) -> Q::Value {
|
||||||
debug!("ty::query::get_query<{}>(key={:?}, span={:?})", Q::NAME.as_str(), key, span);
|
debug!("ty::query::get_query<{}>(key={:?}, span={:?})", Q::NAME, key, span);
|
||||||
|
|
||||||
let job = match JobOwner::try_get(self, span, &key) {
|
let job = match JobOwner::try_get(self, span, &key) {
|
||||||
TryGetJob::NotYetStarted(job) => job,
|
TryGetJob::NotYetStarted(job) => job,
|
||||||
|
@ -366,7 +366,7 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
}
|
}
|
||||||
|
|
||||||
if Q::ANON {
|
if Q::ANON {
|
||||||
let prof_timer = self.prof.query_provider(Q::NAME);
|
let prof_timer = self.prof.query_provider();
|
||||||
|
|
||||||
let ((result, dep_node_index), diagnostics) = with_diagnostics(|diagnostics| {
|
let ((result, dep_node_index), diagnostics) = with_diagnostics(|diagnostics| {
|
||||||
self.start_query(job.job.clone(), diagnostics, |tcx| {
|
self.start_query(job.job.clone(), diagnostics, |tcx| {
|
||||||
|
@ -374,7 +374,7 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
})
|
})
|
||||||
});
|
});
|
||||||
|
|
||||||
drop(prof_timer);
|
prof_timer.finish_with_query_invocation_id(dep_node_index.into());
|
||||||
|
|
||||||
self.dep_graph.read_index(dep_node_index);
|
self.dep_graph.read_index(dep_node_index);
|
||||||
|
|
||||||
|
@ -436,8 +436,9 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
let result = if Q::cache_on_disk(self, key.clone(), None)
|
let result = if Q::cache_on_disk(self, key.clone(), None)
|
||||||
&& self.sess.opts.debugging_opts.incremental_queries
|
&& self.sess.opts.debugging_opts.incremental_queries
|
||||||
{
|
{
|
||||||
let _prof_timer = self.prof.incr_cache_loading(Q::NAME);
|
let prof_timer = self.prof.incr_cache_loading();
|
||||||
let result = Q::try_load_from_disk(self, prev_dep_node_index);
|
let result = Q::try_load_from_disk(self, prev_dep_node_index);
|
||||||
|
prof_timer.finish_with_query_invocation_id(dep_node_index.into());
|
||||||
|
|
||||||
// We always expect to find a cached result for things that
|
// We always expect to find a cached result for things that
|
||||||
// can be forced from `DepNode`.
|
// can be forced from `DepNode`.
|
||||||
|
@ -457,11 +458,13 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
} else {
|
} else {
|
||||||
// We could not load a result from the on-disk cache, so
|
// We could not load a result from the on-disk cache, so
|
||||||
// recompute.
|
// recompute.
|
||||||
let _prof_timer = self.prof.query_provider(Q::NAME);
|
let prof_timer = self.prof.query_provider();
|
||||||
|
|
||||||
// The dep-graph for this computation is already in-place.
|
// The dep-graph for this computation is already in-place.
|
||||||
let result = self.dep_graph.with_ignore(|| Q::compute(self, key));
|
let result = self.dep_graph.with_ignore(|| Q::compute(self, key));
|
||||||
|
|
||||||
|
prof_timer.finish_with_query_invocation_id(dep_node_index.into());
|
||||||
|
|
||||||
result
|
result
|
||||||
};
|
};
|
||||||
|
|
||||||
|
@ -523,7 +526,7 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
dep_node
|
dep_node
|
||||||
);
|
);
|
||||||
|
|
||||||
let prof_timer = self.prof.query_provider(Q::NAME);
|
let prof_timer = self.prof.query_provider();
|
||||||
|
|
||||||
let ((result, dep_node_index), diagnostics) = with_diagnostics(|diagnostics| {
|
let ((result, dep_node_index), diagnostics) = with_diagnostics(|diagnostics| {
|
||||||
self.start_query(job.job.clone(), diagnostics, |tcx| {
|
self.start_query(job.job.clone(), diagnostics, |tcx| {
|
||||||
|
@ -541,7 +544,7 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
})
|
})
|
||||||
});
|
});
|
||||||
|
|
||||||
drop(prof_timer);
|
prof_timer.finish_with_query_invocation_id(dep_node_index.into());
|
||||||
|
|
||||||
if unlikely!(!diagnostics.is_empty()) {
|
if unlikely!(!diagnostics.is_empty()) {
|
||||||
if dep_node.kind != crate::dep_graph::DepKind::Null {
|
if dep_node.kind != crate::dep_graph::DepKind::Null {
|
||||||
|
@ -572,17 +575,19 @@ impl<'tcx> TyCtxt<'tcx> {
|
||||||
|
|
||||||
let dep_node = Q::to_dep_node(self, &key);
|
let dep_node = Q::to_dep_node(self, &key);
|
||||||
|
|
||||||
if self.dep_graph.try_mark_green_and_read(self, &dep_node).is_none() {
|
match self.dep_graph.try_mark_green_and_read(self, &dep_node) {
|
||||||
|
None => {
|
||||||
// A None return from `try_mark_green_and_read` means that this is either
|
// A None return from `try_mark_green_and_read` means that this is either
|
||||||
// a new dep node or that the dep node has already been marked red.
|
// a new dep node or that the dep node has already been marked red.
|
||||||
// Either way, we can't call `dep_graph.read()` as we don't have the
|
// Either way, we can't call `dep_graph.read()` as we don't have the
|
||||||
// DepNodeIndex. We must invoke the query itself. The performance cost
|
// DepNodeIndex. We must invoke the query itself. The performance cost
|
||||||
// this introduces should be negligible as we'll immediately hit the
|
// this introduces should be negligible as we'll immediately hit the
|
||||||
// in-memory cache, or another query down the line will.
|
// in-memory cache, or another query down the line will.
|
||||||
|
|
||||||
let _ = self.get_query::<Q>(DUMMY_SP, key);
|
let _ = self.get_query::<Q>(DUMMY_SP, key);
|
||||||
} else {
|
}
|
||||||
self.prof.query_cache_hit(Q::NAME);
|
Some((_, dep_node_index)) => {
|
||||||
|
self.prof.query_cache_hit(dep_node_index.into());
|
||||||
|
}
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -696,6 +701,42 @@ macro_rules! define_queries_inner {
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
|
/// All self-profiling events generated by the query engine use a
|
||||||
|
/// virtual `StringId`s for their `event_id`. This method makes all
|
||||||
|
/// those virtual `StringId`s point to actual strings.
|
||||||
|
///
|
||||||
|
/// If we are recording only summary data, the ids will point to
|
||||||
|
/// just the query names. If we are recording query keys too, we
|
||||||
|
/// allocate the corresponding strings here. (The latter is not yet
|
||||||
|
/// implemented.)
|
||||||
|
pub fn allocate_self_profile_query_strings(
|
||||||
|
&self,
|
||||||
|
profiler: &rustc_data_structures::profiling::SelfProfiler
|
||||||
|
) {
|
||||||
|
// Walk the entire query cache and allocate the appropriate
|
||||||
|
// string representation. Each cache entry is uniquely
|
||||||
|
// identified by its dep_node_index.
|
||||||
|
$({
|
||||||
|
let query_name_string_id =
|
||||||
|
profiler.get_or_alloc_cached_string(stringify!($name));
|
||||||
|
|
||||||
|
let result_cache = self.$name.lock_shards();
|
||||||
|
|
||||||
|
for shard in result_cache.iter() {
|
||||||
|
let query_invocation_ids = shard
|
||||||
|
.results
|
||||||
|
.values()
|
||||||
|
.map(|v| v.index)
|
||||||
|
.map(|dep_node_index| dep_node_index.into());
|
||||||
|
|
||||||
|
profiler.bulk_map_query_invocation_id_to_single_string(
|
||||||
|
query_invocation_ids,
|
||||||
|
query_name_string_id
|
||||||
|
);
|
||||||
|
}
|
||||||
|
})*
|
||||||
|
}
|
||||||
|
|
||||||
#[cfg(parallel_compiler)]
|
#[cfg(parallel_compiler)]
|
||||||
pub fn collect_active_jobs(&self) -> Vec<Lrc<QueryJob<$tcx>>> {
|
pub fn collect_active_jobs(&self) -> Vec<Lrc<QueryJob<$tcx>>> {
|
||||||
let mut jobs = Vec::new();
|
let mut jobs = Vec::new();
|
||||||
|
@ -813,36 +854,6 @@ macro_rules! define_queries_inner {
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
#[allow(nonstandard_style)]
|
|
||||||
#[derive(Clone, Copy)]
|
|
||||||
pub enum QueryName {
|
|
||||||
$($name),*
|
|
||||||
}
|
|
||||||
|
|
||||||
impl rustc_data_structures::profiling::QueryName for QueryName {
|
|
||||||
fn discriminant(self) -> std::mem::Discriminant<QueryName> {
|
|
||||||
std::mem::discriminant(&self)
|
|
||||||
}
|
|
||||||
|
|
||||||
fn as_str(self) -> &'static str {
|
|
||||||
QueryName::as_str(&self)
|
|
||||||
}
|
|
||||||
}
|
|
||||||
|
|
||||||
impl QueryName {
|
|
||||||
pub fn register_with_profiler(
|
|
||||||
profiler: &rustc_data_structures::profiling::SelfProfiler,
|
|
||||||
) {
|
|
||||||
$(profiler.register_query_name(QueryName::$name);)*
|
|
||||||
}
|
|
||||||
|
|
||||||
pub fn as_str(&self) -> &'static str {
|
|
||||||
match self {
|
|
||||||
$(QueryName::$name => stringify!($name),)*
|
|
||||||
}
|
|
||||||
}
|
|
||||||
}
|
|
||||||
|
|
||||||
#[allow(nonstandard_style)]
|
#[allow(nonstandard_style)]
|
||||||
#[derive(Clone, Debug)]
|
#[derive(Clone, Debug)]
|
||||||
pub enum Query<$tcx> {
|
pub enum Query<$tcx> {
|
||||||
|
@ -883,12 +894,6 @@ macro_rules! define_queries_inner {
|
||||||
$(Query::$name(key) => key.default_span(tcx),)*
|
$(Query::$name(key) => key.default_span(tcx),)*
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
pub fn query_name(&self) -> QueryName {
|
|
||||||
match self {
|
|
||||||
$(Query::$name(_) => QueryName::$name,)*
|
|
||||||
}
|
|
||||||
}
|
|
||||||
}
|
}
|
||||||
|
|
||||||
impl<'a, $tcx> HashStable<StableHashingContext<'a>> for Query<$tcx> {
|
impl<'a, $tcx> HashStable<StableHashingContext<'a>> for Query<$tcx> {
|
||||||
|
@ -923,7 +928,7 @@ macro_rules! define_queries_inner {
|
||||||
type Key = $K;
|
type Key = $K;
|
||||||
type Value = $V;
|
type Value = $V;
|
||||||
|
|
||||||
const NAME: QueryName = QueryName::$name;
|
const NAME: &'static str = stringify!($name);
|
||||||
const CATEGORY: ProfileCategory = $category;
|
const CATEGORY: ProfileCategory = $category;
|
||||||
}
|
}
|
||||||
|
|
||||||
|
|
|
@ -334,7 +334,11 @@ pub fn from_immediate<'a, 'tcx, Bx: BuilderMethods<'a, 'tcx>>(
|
||||||
bx: &mut Bx,
|
bx: &mut Bx,
|
||||||
val: Bx::Value,
|
val: Bx::Value,
|
||||||
) -> Bx::Value {
|
) -> Bx::Value {
|
||||||
if bx.cx().val_ty(val) == bx.cx().type_i1() { bx.zext(val, bx.cx().type_i8()) } else { val }
|
if bx.cx().val_ty(val) == bx.cx().type_i1() {
|
||||||
|
bx.zext(val, bx.cx().type_i8())
|
||||||
|
} else {
|
||||||
|
val
|
||||||
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
pub fn to_immediate<'a, 'tcx, Bx: BuilderMethods<'a, 'tcx>>(
|
pub fn to_immediate<'a, 'tcx, Bx: BuilderMethods<'a, 'tcx>>(
|
||||||
|
@ -519,7 +523,7 @@ pub fn codegen_crate<B: ExtraBackendMethods>(
|
||||||
|
|
||||||
ongoing_codegen.codegen_finished(tcx);
|
ongoing_codegen.codegen_finished(tcx);
|
||||||
|
|
||||||
assert_and_save_dep_graph(tcx);
|
finalize_tcx(tcx);
|
||||||
|
|
||||||
ongoing_codegen.check_for_errors(tcx.sess);
|
ongoing_codegen.check_for_errors(tcx.sess);
|
||||||
|
|
||||||
|
@ -660,7 +664,8 @@ pub fn codegen_crate<B: ExtraBackendMethods>(
|
||||||
|
|
||||||
ongoing_codegen.check_for_errors(tcx.sess);
|
ongoing_codegen.check_for_errors(tcx.sess);
|
||||||
|
|
||||||
assert_and_save_dep_graph(tcx);
|
finalize_tcx(tcx);
|
||||||
|
|
||||||
ongoing_codegen.into_inner()
|
ongoing_codegen.into_inner()
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -711,10 +716,16 @@ impl<B: ExtraBackendMethods> Drop for AbortCodegenOnDrop<B> {
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
fn assert_and_save_dep_graph(tcx: TyCtxt<'_>) {
|
fn finalize_tcx(tcx: TyCtxt<'_>) {
|
||||||
tcx.sess.time("assert_dep_graph", || ::rustc_incremental::assert_dep_graph(tcx));
|
tcx.sess.time("assert_dep_graph", || ::rustc_incremental::assert_dep_graph(tcx));
|
||||||
|
|
||||||
tcx.sess.time("serialize_dep_graph", || ::rustc_incremental::save_dep_graph(tcx));
|
tcx.sess.time("serialize_dep_graph", || ::rustc_incremental::save_dep_graph(tcx));
|
||||||
|
|
||||||
|
// We assume that no queries are run past here. If there are new queries
|
||||||
|
// after this point, they'll show up as "<unknown>" in self-profiling data.
|
||||||
|
tcx.prof.with_profiler(|profiler| {
|
||||||
|
let _prof_timer = tcx.prof.generic_activity("self_profile_alloc_query_strings");
|
||||||
|
tcx.queries.allocate_self_profile_query_strings(profiler);
|
||||||
|
});
|
||||||
}
|
}
|
||||||
|
|
||||||
impl CrateInfo {
|
impl CrateInfo {
|
||||||
|
@ -876,7 +887,11 @@ fn determine_cgu_reuse<'tcx>(tcx: TyCtxt<'tcx>, cgu: &CodegenUnit<'tcx>) -> CguR
|
||||||
|
|
||||||
if tcx.dep_graph.try_mark_green(tcx, &dep_node).is_some() {
|
if tcx.dep_graph.try_mark_green(tcx, &dep_node).is_some() {
|
||||||
// We can re-use either the pre- or the post-thinlto state
|
// We can re-use either the pre- or the post-thinlto state
|
||||||
if tcx.sess.lto() != Lto::No { CguReuse::PreLto } else { CguReuse::PostLto }
|
if tcx.sess.lto() != Lto::No {
|
||||||
|
CguReuse::PreLto
|
||||||
|
} else {
|
||||||
|
CguReuse::PostLto
|
||||||
|
}
|
||||||
} else {
|
} else {
|
||||||
CguReuse::No
|
CguReuse::No
|
||||||
}
|
}
|
||||||
|
|
|
@ -26,7 +26,7 @@ rustc-hash = "1.0.1"
|
||||||
smallvec = { version = "1.0", features = ["union", "may_dangle"] }
|
smallvec = { version = "1.0", features = ["union", "may_dangle"] }
|
||||||
rustc_index = { path = "../librustc_index", package = "rustc_index" }
|
rustc_index = { path = "../librustc_index", package = "rustc_index" }
|
||||||
bitflags = "1.2.1"
|
bitflags = "1.2.1"
|
||||||
measureme = "0.5"
|
measureme = "0.6.0"
|
||||||
|
|
||||||
[dependencies.parking_lot]
|
[dependencies.parking_lot]
|
||||||
version = "0.9"
|
version = "0.9"
|
||||||
|
|
|
@ -1,6 +1,90 @@
|
||||||
|
//! # Rust Compiler Self-Profiling
|
||||||
|
//!
|
||||||
|
//! This module implements the basic framework for the compiler's self-
|
||||||
|
//! profiling support. It provides the `SelfProfiler` type which enables
|
||||||
|
//! recording "events". An event is something that starts and ends at a given
|
||||||
|
//! point in time and has an ID and a kind attached to it. This allows for
|
||||||
|
//! tracing the compiler's activity.
|
||||||
|
//!
|
||||||
|
//! Internally this module uses the custom tailored [measureme][mm] crate for
|
||||||
|
//! efficiently recording events to disk in a compact format that can be
|
||||||
|
//! post-processed and analyzed by the suite of tools in the `measureme`
|
||||||
|
//! project. The highest priority for the tracing framework is on incurring as
|
||||||
|
//! little overhead as possible.
|
||||||
|
//!
|
||||||
|
//!
|
||||||
|
//! ## Event Overview
|
||||||
|
//!
|
||||||
|
//! Events have a few properties:
|
||||||
|
//!
|
||||||
|
//! - The `event_kind` designates the broad category of an event (e.g. does it
|
||||||
|
//! correspond to the execution of a query provider or to loading something
|
||||||
|
//! from the incr. comp. on-disk cache, etc).
|
||||||
|
//! - The `event_id` designates the query invocation or function call it
|
||||||
|
//! corresponds to, possibly including the query key or function arguments.
|
||||||
|
//! - Each event stores the ID of the thread it was recorded on.
|
||||||
|
//! - The timestamp stores beginning and end of the event, or the single point
|
||||||
|
//! in time it occurred at for "instant" events.
|
||||||
|
//!
|
||||||
|
//!
|
||||||
|
//! ## Event Filtering
|
||||||
|
//!
|
||||||
|
//! Event generation can be filtered by event kind. Recording all possible
|
||||||
|
//! events generates a lot of data, much of which is not needed for most kinds
|
||||||
|
//! of analysis. So, in order to keep overhead as low as possible for a given
|
||||||
|
//! use case, the `SelfProfiler` will only record the kinds of events that
|
||||||
|
//! pass the filter specified as a command line argument to the compiler.
|
||||||
|
//!
|
||||||
|
//!
|
||||||
|
//! ## `event_id` Assignment
|
||||||
|
//!
|
||||||
|
//! As far as `measureme` is concerned, `event_id`s are just strings. However,
|
||||||
|
//! it would incur way too much overhead to generate and persist each `event_id`
|
||||||
|
//! string at the point where the event is recorded. In order to make this more
|
||||||
|
//! efficient `measureme` has two features:
|
||||||
|
//!
|
||||||
|
//! - Strings can share their content, so that re-occurring parts don't have to
|
||||||
|
//! be copied over and over again. One allocates a string in `measureme` and
|
||||||
|
//! gets back a `StringId`. This `StringId` is then used to refer to that
|
||||||
|
//! string. `measureme` strings are actually DAGs of string components so that
|
||||||
|
//! arbitrary sharing of substrings can be done efficiently. This is useful
|
||||||
|
//! because `event_id`s contain lots of redundant text like query names or
|
||||||
|
//! def-path components.
|
||||||
|
//!
|
||||||
|
//! - `StringId`s can be "virtual" which means that the client picks a numeric
|
||||||
|
//! ID according to some application-specific scheme and can later make that
|
||||||
|
//! ID be mapped to an actual string. This is used to cheaply generate
|
||||||
|
//! `event_id`s while the events actually occur, causing little timing
|
||||||
|
//! distortion, and then later map those `StringId`s, in bulk, to actual
|
||||||
|
//! `event_id` strings. This way the largest part of tracing overhead is
|
||||||
|
//! localized to one contiguous chunk of time.
|
||||||
|
//!
|
||||||
|
//! How are these `event_id`s generated in the compiler? For things that occur
|
||||||
|
//! infrequently (e.g. "generic activities"), we just allocate the string the
|
||||||
|
//! first time it is used and then keep the `StringId` in a hash table. This
|
||||||
|
//! is implemented in `SelfProfiler::get_or_alloc_cached_string()`.
|
||||||
|
//!
|
||||||
|
//! For queries it gets more interesting: First we need a unique numeric ID for
|
||||||
|
//! each query invocation (the `QueryInvocationId`). This ID is used as the
|
||||||
|
//! virtual `StringId` we use as `event_id` for a given event. This ID has to
|
||||||
|
//! be available both when the query is executed and later, together with the
|
||||||
|
//! query key, when we allocate the actual `event_id` strings in bulk.
|
||||||
|
//!
|
||||||
|
//! We could make the compiler generate and keep track of such an ID for each
|
||||||
|
//! query invocation but luckily we already have something that fits all the
|
||||||
|
//! the requirements: the query's `DepNodeIndex`. So we use the numeric value
|
||||||
|
//! of the `DepNodeIndex` as `event_id` when recording the event and then,
|
||||||
|
//! just before the query context is dropped, we walk the entire query cache
|
||||||
|
//! (which stores the `DepNodeIndex` along with the query key for each
|
||||||
|
//! invocation) and allocate the corresponding strings together with a mapping
|
||||||
|
//! for `DepNodeIndex as StringId`.
|
||||||
|
//!
|
||||||
|
//! [mm]: https://github.com/rust-lang/measureme/
|
||||||
|
|
||||||
|
use crate::fx::FxHashMap;
|
||||||
|
|
||||||
use std::error::Error;
|
use std::error::Error;
|
||||||
use std::fs;
|
use std::fs;
|
||||||
use std::mem::{self, Discriminant};
|
|
||||||
use std::path::Path;
|
use std::path::Path;
|
||||||
use std::process;
|
use std::process;
|
||||||
use std::sync::Arc;
|
use std::sync::Arc;
|
||||||
|
@ -9,6 +93,7 @@ use std::time::{Duration, Instant};
|
||||||
use std::u32;
|
use std::u32;
|
||||||
|
|
||||||
use measureme::StringId;
|
use measureme::StringId;
|
||||||
|
use parking_lot::RwLock;
|
||||||
|
|
||||||
/// MmapSerializatioSink is faster on macOS and Linux
|
/// MmapSerializatioSink is faster on macOS and Linux
|
||||||
/// but FileSerializationSink is faster on Windows
|
/// but FileSerializationSink is faster on Windows
|
||||||
|
@ -19,11 +104,6 @@ type SerializationSink = measureme::FileSerializationSink;
|
||||||
|
|
||||||
type Profiler = measureme::Profiler<SerializationSink>;
|
type Profiler = measureme::Profiler<SerializationSink>;
|
||||||
|
|
||||||
pub trait QueryName: Sized + Copy {
|
|
||||||
fn discriminant(self) -> Discriminant<Self>;
|
|
||||||
fn as_str(self) -> &'static str;
|
|
||||||
}
|
|
||||||
|
|
||||||
#[derive(Clone, Copy, Debug, PartialEq, Eq, Ord, PartialOrd)]
|
#[derive(Clone, Copy, Debug, PartialEq, Eq, Ord, PartialOrd)]
|
||||||
pub enum ProfileCategory {
|
pub enum ProfileCategory {
|
||||||
Parsing,
|
Parsing,
|
||||||
|
@ -65,9 +145,12 @@ const EVENT_FILTERS_BY_NAME: &[(&str, EventFilter)] = &[
|
||||||
];
|
];
|
||||||
|
|
||||||
fn thread_id_to_u32(tid: ThreadId) -> u32 {
|
fn thread_id_to_u32(tid: ThreadId) -> u32 {
|
||||||
unsafe { mem::transmute::<ThreadId, u64>(tid) as u32 }
|
unsafe { std::mem::transmute::<ThreadId, u64>(tid) as u32 }
|
||||||
}
|
}
|
||||||
|
|
||||||
|
/// Something that uniquely identifies a query invocation.
|
||||||
|
pub struct QueryInvocationId(pub u32);
|
||||||
|
|
||||||
/// A reference to the SelfProfiler. It can be cloned and sent across thread
|
/// A reference to the SelfProfiler. It can be cloned and sent across thread
|
||||||
/// boundaries at will.
|
/// boundaries at will.
|
||||||
#[derive(Clone)]
|
#[derive(Clone)]
|
||||||
|
@ -167,29 +250,32 @@ impl SelfProfilerRef {
|
||||||
/// Start profiling a generic activity. Profiling continues until the
|
/// Start profiling a generic activity. Profiling continues until the
|
||||||
/// TimingGuard returned from this call is dropped.
|
/// TimingGuard returned from this call is dropped.
|
||||||
#[inline(always)]
|
#[inline(always)]
|
||||||
pub fn generic_activity(&self, event_id: &str) -> TimingGuard<'_> {
|
pub fn generic_activity(&self, event_id: &'static str) -> TimingGuard<'_> {
|
||||||
self.exec(EventFilter::GENERIC_ACTIVITIES, |profiler| {
|
self.exec(EventFilter::GENERIC_ACTIVITIES, |profiler| {
|
||||||
let event_id = profiler.profiler.alloc_string(event_id);
|
let event_id = profiler.get_or_alloc_cached_string(event_id);
|
||||||
TimingGuard::start(profiler, profiler.generic_activity_event_kind, event_id)
|
TimingGuard::start(
|
||||||
|
profiler,
|
||||||
|
profiler.generic_activity_event_kind,
|
||||||
|
event_id
|
||||||
|
)
|
||||||
})
|
})
|
||||||
}
|
}
|
||||||
|
|
||||||
/// Start profiling a query provider. Profiling continues until the
|
/// Start profiling a query provider. Profiling continues until the
|
||||||
/// TimingGuard returned from this call is dropped.
|
/// TimingGuard returned from this call is dropped.
|
||||||
#[inline(always)]
|
#[inline(always)]
|
||||||
pub fn query_provider(&self, query_name: impl QueryName) -> TimingGuard<'_> {
|
pub fn query_provider(&self) -> TimingGuard<'_> {
|
||||||
self.exec(EventFilter::QUERY_PROVIDERS, |profiler| {
|
self.exec(EventFilter::QUERY_PROVIDERS, |profiler| {
|
||||||
let event_id = SelfProfiler::get_query_name_string_id(query_name);
|
TimingGuard::start(profiler, profiler.query_event_kind, StringId::INVALID)
|
||||||
TimingGuard::start(profiler, profiler.query_event_kind, event_id)
|
|
||||||
})
|
})
|
||||||
}
|
}
|
||||||
|
|
||||||
/// Record a query in-memory cache hit.
|
/// Record a query in-memory cache hit.
|
||||||
#[inline(always)]
|
#[inline(always)]
|
||||||
pub fn query_cache_hit(&self, query_name: impl QueryName) {
|
pub fn query_cache_hit(&self, query_invocation_id: QueryInvocationId) {
|
||||||
self.instant_query_event(
|
self.instant_query_event(
|
||||||
|profiler| profiler.query_cache_hit_event_kind,
|
|profiler| profiler.query_cache_hit_event_kind,
|
||||||
query_name,
|
query_invocation_id,
|
||||||
EventFilter::QUERY_CACHE_HITS,
|
EventFilter::QUERY_CACHE_HITS,
|
||||||
);
|
);
|
||||||
}
|
}
|
||||||
|
@ -198,10 +284,13 @@ impl SelfProfilerRef {
|
||||||
/// Profiling continues until the TimingGuard returned from this call is
|
/// Profiling continues until the TimingGuard returned from this call is
|
||||||
/// dropped.
|
/// dropped.
|
||||||
#[inline(always)]
|
#[inline(always)]
|
||||||
pub fn query_blocked(&self, query_name: impl QueryName) -> TimingGuard<'_> {
|
pub fn query_blocked(&self) -> TimingGuard<'_> {
|
||||||
self.exec(EventFilter::QUERY_BLOCKED, |profiler| {
|
self.exec(EventFilter::QUERY_BLOCKED, |profiler| {
|
||||||
let event_id = SelfProfiler::get_query_name_string_id(query_name);
|
TimingGuard::start(
|
||||||
TimingGuard::start(profiler, profiler.query_blocked_event_kind, event_id)
|
profiler,
|
||||||
|
profiler.query_blocked_event_kind,
|
||||||
|
StringId::INVALID,
|
||||||
|
)
|
||||||
})
|
})
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -209,10 +298,13 @@ impl SelfProfilerRef {
|
||||||
/// incremental compilation on-disk cache. Profiling continues until the
|
/// incremental compilation on-disk cache. Profiling continues until the
|
||||||
/// TimingGuard returned from this call is dropped.
|
/// TimingGuard returned from this call is dropped.
|
||||||
#[inline(always)]
|
#[inline(always)]
|
||||||
pub fn incr_cache_loading(&self, query_name: impl QueryName) -> TimingGuard<'_> {
|
pub fn incr_cache_loading(&self) -> TimingGuard<'_> {
|
||||||
self.exec(EventFilter::INCR_CACHE_LOADS, |profiler| {
|
self.exec(EventFilter::INCR_CACHE_LOADS, |profiler| {
|
||||||
let event_id = SelfProfiler::get_query_name_string_id(query_name);
|
TimingGuard::start(
|
||||||
TimingGuard::start(profiler, profiler.incremental_load_result_event_kind, event_id)
|
profiler,
|
||||||
|
profiler.incremental_load_result_event_kind,
|
||||||
|
StringId::INVALID,
|
||||||
|
)
|
||||||
})
|
})
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -220,11 +312,11 @@ impl SelfProfilerRef {
|
||||||
fn instant_query_event(
|
fn instant_query_event(
|
||||||
&self,
|
&self,
|
||||||
event_kind: fn(&SelfProfiler) -> StringId,
|
event_kind: fn(&SelfProfiler) -> StringId,
|
||||||
query_name: impl QueryName,
|
query_invocation_id: QueryInvocationId,
|
||||||
event_filter: EventFilter,
|
event_filter: EventFilter,
|
||||||
) {
|
) {
|
||||||
drop(self.exec(event_filter, |profiler| {
|
drop(self.exec(event_filter, |profiler| {
|
||||||
let event_id = SelfProfiler::get_query_name_string_id(query_name);
|
let event_id = StringId::new_virtual(query_invocation_id.0);
|
||||||
let thread_id = thread_id_to_u32(std::thread::current().id());
|
let thread_id = thread_id_to_u32(std::thread::current().id());
|
||||||
|
|
||||||
profiler.profiler.record_instant_event(event_kind(profiler), event_id, thread_id);
|
profiler.profiler.record_instant_event(event_kind(profiler), event_id, thread_id);
|
||||||
|
@ -233,7 +325,7 @@ impl SelfProfilerRef {
|
||||||
}));
|
}));
|
||||||
}
|
}
|
||||||
|
|
||||||
pub fn register_queries(&self, f: impl FnOnce(&SelfProfiler)) {
|
pub fn with_profiler(&self, f: impl FnOnce(&SelfProfiler)) {
|
||||||
if let Some(profiler) = &self.profiler {
|
if let Some(profiler) = &self.profiler {
|
||||||
f(&profiler)
|
f(&profiler)
|
||||||
}
|
}
|
||||||
|
@ -243,6 +335,9 @@ impl SelfProfilerRef {
|
||||||
pub struct SelfProfiler {
|
pub struct SelfProfiler {
|
||||||
profiler: Profiler,
|
profiler: Profiler,
|
||||||
event_filter_mask: EventFilter,
|
event_filter_mask: EventFilter,
|
||||||
|
|
||||||
|
string_cache: RwLock<FxHashMap<&'static str, StringId>>,
|
||||||
|
|
||||||
query_event_kind: StringId,
|
query_event_kind: StringId,
|
||||||
generic_activity_event_kind: StringId,
|
generic_activity_event_kind: StringId,
|
||||||
incremental_load_result_event_kind: StringId,
|
incremental_load_result_event_kind: StringId,
|
||||||
|
@ -305,6 +400,7 @@ impl SelfProfiler {
|
||||||
Ok(SelfProfiler {
|
Ok(SelfProfiler {
|
||||||
profiler,
|
profiler,
|
||||||
event_filter_mask,
|
event_filter_mask,
|
||||||
|
string_cache: RwLock::new(FxHashMap::default()),
|
||||||
query_event_kind,
|
query_event_kind,
|
||||||
generic_activity_event_kind,
|
generic_activity_event_kind,
|
||||||
incremental_load_result_event_kind,
|
incremental_load_result_event_kind,
|
||||||
|
@ -313,16 +409,41 @@ impl SelfProfiler {
|
||||||
})
|
})
|
||||||
}
|
}
|
||||||
|
|
||||||
fn get_query_name_string_id(query_name: impl QueryName) -> StringId {
|
pub fn get_or_alloc_cached_string(&self, s: &'static str) -> StringId {
|
||||||
let discriminant =
|
// Only acquire a read-lock first since we assume that the string is
|
||||||
unsafe { mem::transmute::<Discriminant<_>, u64>(query_name.discriminant()) };
|
// already present in the common case.
|
||||||
|
{
|
||||||
|
let string_cache = self.string_cache.read();
|
||||||
|
|
||||||
StringId::reserved(discriminant as u32)
|
if let Some(&id) = string_cache.get(s) {
|
||||||
|
return id
|
||||||
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
pub fn register_query_name(&self, query_name: impl QueryName) {
|
let mut string_cache = self.string_cache.write();
|
||||||
let id = SelfProfiler::get_query_name_string_id(query_name);
|
// Check if the string has already been added in the small time window
|
||||||
self.profiler.alloc_string_with_reserved_id(id, query_name.as_str());
|
// between dropping the read lock and acquiring the write lock.
|
||||||
|
*string_cache.entry(s).or_insert_with(|| self.profiler.alloc_string(s))
|
||||||
|
}
|
||||||
|
|
||||||
|
pub fn map_query_invocation_id_to_string(
|
||||||
|
&self,
|
||||||
|
from: QueryInvocationId,
|
||||||
|
to: StringId
|
||||||
|
) {
|
||||||
|
let from = StringId::new_virtual(from.0);
|
||||||
|
self.profiler.map_virtual_to_concrete_string(from, to);
|
||||||
|
}
|
||||||
|
|
||||||
|
pub fn bulk_map_query_invocation_id_to_single_string<I>(
|
||||||
|
&self,
|
||||||
|
from: I,
|
||||||
|
to: StringId
|
||||||
|
)
|
||||||
|
where I: Iterator<Item=QueryInvocationId> + ExactSizeIterator
|
||||||
|
{
|
||||||
|
let from = from.map(|qid| StringId::new_virtual(qid.0));
|
||||||
|
self.profiler.bulk_map_virtual_to_single_concrete_string(from, to);
|
||||||
}
|
}
|
||||||
}
|
}
|
||||||
|
|
||||||
|
@ -343,6 +464,14 @@ impl<'a> TimingGuard<'a> {
|
||||||
TimingGuard(Some(timing_guard))
|
TimingGuard(Some(timing_guard))
|
||||||
}
|
}
|
||||||
|
|
||||||
|
#[inline]
|
||||||
|
pub fn finish_with_query_invocation_id(self, query_invocation_id: QueryInvocationId) {
|
||||||
|
if let Some(guard) = self.0 {
|
||||||
|
let event_id = StringId::new_virtual(query_invocation_id.0);
|
||||||
|
guard.finish_with_override_event_id(event_id);
|
||||||
|
}
|
||||||
|
}
|
||||||
|
|
||||||
#[inline]
|
#[inline]
|
||||||
pub fn none() -> TimingGuard<'a> {
|
pub fn none() -> TimingGuard<'a> {
|
||||||
TimingGuard(None)
|
TimingGuard(None)
|
||||||
|
|
|
@ -71,10 +71,6 @@ pub fn create_session(
|
||||||
lint_caps,
|
lint_caps,
|
||||||
);
|
);
|
||||||
|
|
||||||
sess.prof.register_queries(|profiler| {
|
|
||||||
rustc::ty::query::QueryName::register_with_profiler(&profiler);
|
|
||||||
});
|
|
||||||
|
|
||||||
let codegen_backend = get_codegen_backend(&sess);
|
let codegen_backend = get_codegen_backend(&sess);
|
||||||
|
|
||||||
let mut cfg = config::build_configuration(&sess, config::to_crate_config(cfg));
|
let mut cfg = config::build_configuration(&sess, config::to_crate_config(cfg));
|
||||||
|
|
Loading…
Add table
Add a link
Reference in a new issue