| 1 | //! ## Per-Layer Filtering |
| 2 | //! |
| 3 | //! Per-layer filters permit individual `Layer`s to have their own filter |
| 4 | //! configurations without interfering with other `Layer`s. |
| 5 | //! |
| 6 | //! This module is not public; the public APIs defined in this module are |
| 7 | //! re-exported in the top-level `filter` module. Therefore, this documentation |
| 8 | //! primarily concerns the internal implementation details. For the user-facing |
| 9 | //! public API documentation, see the individual public types in this module, as |
| 10 | //! well as the, see the `Layer` trait documentation's [per-layer filtering |
| 11 | //! section]][1]. |
| 12 | //! |
| 13 | //! ## How does per-layer filtering work? |
| 14 | //! |
| 15 | //! As described in the API documentation, the [`Filter`] trait defines a |
| 16 | //! filtering strategy for a per-layer filter. We expect there will be a variety |
| 17 | //! of implementations of [`Filter`], both in `tracing-subscriber` and in user |
| 18 | //! code. |
| 19 | //! |
| 20 | //! To actually *use* a [`Filter`] implementation, it is combined with a |
| 21 | //! [`Layer`] by the [`Filtered`] struct defined in this module. [`Filtered`] |
| 22 | //! implements [`Layer`] by calling into the wrapped [`Layer`], or not, based on |
| 23 | //! the filtering strategy. While there will be a variety of types that implement |
| 24 | //! [`Filter`], all actual *uses* of per-layer filtering will occur through the |
| 25 | //! [`Filtered`] struct. Therefore, most of the implementation details live |
| 26 | //! there. |
| 27 | //! |
| 28 | //! [1]: crate::layer#per-layer-filtering |
| 29 | //! [`Filter`]: crate::layer::Filter |
| 30 | use crate::{ |
| 31 | filter::LevelFilter, |
| 32 | layer::{self, Context, Layer}, |
| 33 | registry, |
| 34 | }; |
| 35 | use std::{ |
| 36 | any::TypeId, |
| 37 | cell::{Cell, RefCell}, |
| 38 | fmt, |
| 39 | marker::PhantomData, |
| 40 | ops::Deref, |
| 41 | sync::Arc, |
| 42 | thread_local, |
| 43 | }; |
| 44 | use tracing_core::{ |
| 45 | span, |
| 46 | subscriber::{Interest, Subscriber}, |
| 47 | Dispatch, Event, Metadata, |
| 48 | }; |
| 49 | pub mod combinator; |
| 50 | |
| 51 | /// A [`Layer`] that wraps an inner [`Layer`] and adds a [`Filter`] which |
| 52 | /// controls what spans and events are enabled for that layer. |
| 53 | /// |
| 54 | /// This is returned by the [`Layer::with_filter`] method. See the |
| 55 | /// [documentation on per-layer filtering][plf] for details. |
| 56 | /// |
| 57 | /// [`Filter`]: crate::layer::Filter |
| 58 | /// [plf]: crate::layer#per-layer-filtering |
| 59 | #[cfg_attr (docsrs, doc(cfg(feature = "registry" )))] |
| 60 | #[derive(Clone)] |
| 61 | pub struct Filtered<L, F, S> { |
| 62 | filter: F, |
| 63 | layer: L, |
| 64 | id: MagicPlfDowncastMarker, |
| 65 | _s: PhantomData<fn(S)>, |
| 66 | } |
| 67 | |
| 68 | /// Uniquely identifies an individual [`Filter`] instance in the context of |
| 69 | /// a [`Subscriber`]. |
| 70 | /// |
| 71 | /// When adding a [`Filtered`] [`Layer`] to a [`Subscriber`], the [`Subscriber`] |
| 72 | /// generates a `FilterId` for that [`Filtered`] layer. The [`Filtered`] layer |
| 73 | /// will then use the generated ID to query whether a particular span was |
| 74 | /// previously enabled by that layer's [`Filter`]. |
| 75 | /// |
| 76 | /// **Note**: Currently, the [`Registry`] type provided by this crate is the |
| 77 | /// **only** [`Subscriber`] implementation capable of participating in per-layer |
| 78 | /// filtering. Therefore, the `FilterId` type cannot currently be constructed by |
| 79 | /// code outside of `tracing-subscriber`. In the future, new APIs will be added to `tracing-subscriber` to |
| 80 | /// allow non-Registry [`Subscriber`]s to also participate in per-layer |
| 81 | /// filtering. When those APIs are added, subscribers will be responsible |
| 82 | /// for generating and assigning `FilterId`s. |
| 83 | /// |
| 84 | /// [`Filter`]: crate::layer::Filter |
| 85 | /// [`Subscriber`]: tracing_core::Subscriber |
| 86 | /// [`Layer`]: crate::layer::Layer |
| 87 | /// [`Registry`]: crate::registry::Registry |
| 88 | #[cfg (feature = "registry" )] |
| 89 | #[cfg_attr (docsrs, doc(cfg(feature = "registry" )))] |
| 90 | #[derive(Copy, Clone)] |
| 91 | pub struct FilterId(u64); |
| 92 | |
| 93 | /// A bitmap tracking which [`FilterId`]s have enabled a given span or |
| 94 | /// event. |
| 95 | /// |
| 96 | /// This is currently a private type that's used exclusively by the |
| 97 | /// [`Registry`]. However, in the future, this may become a public API, in order |
| 98 | /// to allow user subscribers to host [`Filter`]s. |
| 99 | /// |
| 100 | /// [`Registry`]: crate::Registry |
| 101 | /// [`Filter`]: crate::layer::Filter |
| 102 | #[derive(Default, Copy, Clone, Eq, PartialEq)] |
| 103 | pub(crate) struct FilterMap { |
| 104 | bits: u64, |
| 105 | } |
| 106 | |
| 107 | /// The current state of `enabled` calls to per-layer filters on this |
| 108 | /// thread. |
| 109 | /// |
| 110 | /// When `Filtered::enabled` is called, the filter will set the bit |
| 111 | /// corresponding to its ID if the filter will disable the event/span being |
| 112 | /// filtered. When the event or span is recorded, the per-layer filter will |
| 113 | /// check its bit to determine if it disabled that event or span, and skip |
| 114 | /// forwarding the event or span to the inner layer if the bit is set. Once |
| 115 | /// a span or event has been skipped by a per-layer filter, it unsets its |
| 116 | /// bit, so that the `FilterMap` has been cleared for the next set of |
| 117 | /// `enabled` calls. |
| 118 | /// |
| 119 | /// FilterState is also read by the `Registry`, for two reasons: |
| 120 | /// |
| 121 | /// 1. When filtering a span, the Registry must store the `FilterMap` |
| 122 | /// generated by `Filtered::enabled` calls for that span as part of the |
| 123 | /// span's per-span data. This allows `Filtered` layers to determine |
| 124 | /// whether they had previously disabled a given span, and avoid showing it |
| 125 | /// to the wrapped layer if it was disabled. |
| 126 | /// |
| 127 | /// This allows `Filtered` layers to also filter out the spans they |
| 128 | /// disable from span traversals (such as iterating over parents, etc). |
| 129 | /// 2. If all the bits are set, then every per-layer filter has decided it |
| 130 | /// doesn't want to enable that span or event. In that case, the |
| 131 | /// `Registry`'s `enabled` method will return `false`, so that |
| 132 | /// recording a span or event can be skipped entirely. |
| 133 | #[derive(Debug)] |
| 134 | pub(crate) struct FilterState { |
| 135 | enabled: Cell<FilterMap>, |
| 136 | // TODO(eliza): `Interest`s should _probably_ be `Copy`. The only reason |
| 137 | // they're not is our Obsessive Commitment to Forwards-Compatibility. If |
| 138 | // this changes in tracing-core`, we can make this a `Cell` rather than |
| 139 | // `RefCell`... |
| 140 | interest: RefCell<Option<Interest>>, |
| 141 | |
| 142 | #[cfg (debug_assertions)] |
| 143 | counters: DebugCounters, |
| 144 | } |
| 145 | |
| 146 | /// Extra counters added to `FilterState` used only to make debug assertions. |
| 147 | #[cfg (debug_assertions)] |
| 148 | #[derive(Debug, Default)] |
| 149 | struct DebugCounters { |
| 150 | /// How many per-layer filters have participated in the current `enabled` |
| 151 | /// call? |
| 152 | in_filter_pass: Cell<usize>, |
| 153 | |
| 154 | /// How many per-layer filters have participated in the current `register_callsite` |
| 155 | /// call? |
| 156 | in_interest_pass: Cell<usize>, |
| 157 | } |
| 158 | |
| 159 | thread_local! { |
| 160 | pub(crate) static FILTERING: FilterState = FilterState::new(); |
| 161 | } |
| 162 | |
| 163 | /// Extension trait adding [combinators] for combining [`Filter`]. |
| 164 | /// |
| 165 | /// [combinators]: crate::filter::combinator |
| 166 | /// [`Filter`]: crate::layer::Filter |
| 167 | pub trait FilterExt<S>: layer::Filter<S> { |
| 168 | /// Combines this [`Filter`] with another [`Filter`] s so that spans and |
| 169 | /// events are enabled if and only if *both* filters return `true`. |
| 170 | /// |
| 171 | /// # Examples |
| 172 | /// |
| 173 | /// Enabling spans or events if they have both a particular target *and* are |
| 174 | /// above a certain level: |
| 175 | /// |
| 176 | /// ``` |
| 177 | /// use tracing_subscriber::{ |
| 178 | /// filter::{filter_fn, LevelFilter, FilterExt}, |
| 179 | /// prelude::*, |
| 180 | /// }; |
| 181 | /// |
| 182 | /// // Enables spans and events with targets starting with `interesting_target`: |
| 183 | /// let target_filter = filter_fn(|meta| { |
| 184 | /// meta.target().starts_with("interesting_target" ) |
| 185 | /// }); |
| 186 | /// |
| 187 | /// // Enables spans and events with levels `INFO` and below: |
| 188 | /// let level_filter = LevelFilter::INFO; |
| 189 | /// |
| 190 | /// // Combine the two filters together, returning a filter that only enables |
| 191 | /// // spans and events that *both* filters will enable: |
| 192 | /// let filter = target_filter.and(level_filter); |
| 193 | /// |
| 194 | /// tracing_subscriber::registry() |
| 195 | /// .with(tracing_subscriber::fmt::layer().with_filter(filter)) |
| 196 | /// .init(); |
| 197 | /// |
| 198 | /// // This event will *not* be enabled: |
| 199 | /// tracing::info!("an event with an uninteresting target" ); |
| 200 | /// |
| 201 | /// // This event *will* be enabled: |
| 202 | /// tracing::info!(target: "interesting_target" , "a very interesting event" ); |
| 203 | /// |
| 204 | /// // This event will *not* be enabled: |
| 205 | /// tracing::debug!(target: "interesting_target" , "interesting debug event..." ); |
| 206 | /// ``` |
| 207 | /// |
| 208 | /// [`Filter`]: crate::layer::Filter |
| 209 | fn and<B>(self, other: B) -> combinator::And<Self, B, S> |
| 210 | where |
| 211 | Self: Sized, |
| 212 | B: layer::Filter<S>, |
| 213 | { |
| 214 | combinator::And::new(self, other) |
| 215 | } |
| 216 | |
| 217 | /// Combines two [`Filter`]s so that spans and events are enabled if *either* filter |
| 218 | /// returns `true`. |
| 219 | /// |
| 220 | /// # Examples |
| 221 | /// |
| 222 | /// Enabling spans and events at the `INFO` level and above, and all spans |
| 223 | /// and events with a particular target: |
| 224 | /// ``` |
| 225 | /// use tracing_subscriber::{ |
| 226 | /// filter::{filter_fn, LevelFilter, FilterExt}, |
| 227 | /// prelude::*, |
| 228 | /// }; |
| 229 | /// |
| 230 | /// // Enables spans and events with targets starting with `interesting_target`: |
| 231 | /// let target_filter = filter_fn(|meta| { |
| 232 | /// meta.target().starts_with("interesting_target" ) |
| 233 | /// }); |
| 234 | /// |
| 235 | /// // Enables spans and events with levels `INFO` and below: |
| 236 | /// let level_filter = LevelFilter::INFO; |
| 237 | /// |
| 238 | /// // Combine the two filters together so that a span or event is enabled |
| 239 | /// // if it is at INFO or lower, or if it has a target starting with |
| 240 | /// // `interesting_target`. |
| 241 | /// let filter = level_filter.or(target_filter); |
| 242 | /// |
| 243 | /// tracing_subscriber::registry() |
| 244 | /// .with(tracing_subscriber::fmt::layer().with_filter(filter)) |
| 245 | /// .init(); |
| 246 | /// |
| 247 | /// // This event will *not* be enabled: |
| 248 | /// tracing::debug!("an uninteresting event" ); |
| 249 | /// |
| 250 | /// // This event *will* be enabled: |
| 251 | /// tracing::info!("an uninteresting INFO event" ); |
| 252 | /// |
| 253 | /// // This event *will* be enabled: |
| 254 | /// tracing::info!(target: "interesting_target" , "a very interesting event" ); |
| 255 | /// |
| 256 | /// // This event *will* be enabled: |
| 257 | /// tracing::debug!(target: "interesting_target" , "interesting debug event..." ); |
| 258 | /// ``` |
| 259 | /// |
| 260 | /// Enabling a higher level for a particular target by using `or` in |
| 261 | /// conjunction with the [`and`] combinator: |
| 262 | /// |
| 263 | /// ``` |
| 264 | /// use tracing_subscriber::{ |
| 265 | /// filter::{filter_fn, LevelFilter, FilterExt}, |
| 266 | /// prelude::*, |
| 267 | /// }; |
| 268 | /// |
| 269 | /// // This filter will enable spans and events with targets beginning with |
| 270 | /// // `my_crate`: |
| 271 | /// let my_crate = filter_fn(|meta| { |
| 272 | /// meta.target().starts_with("my_crate" ) |
| 273 | /// }); |
| 274 | /// |
| 275 | /// let filter = my_crate |
| 276 | /// // Combine the `my_crate` filter with a `LevelFilter` to produce a |
| 277 | /// // filter that will enable the `INFO` level and lower for spans and |
| 278 | /// // events with `my_crate` targets: |
| 279 | /// .and(LevelFilter::INFO) |
| 280 | /// // If a span or event *doesn't* have a target beginning with |
| 281 | /// // `my_crate`, enable it if it has the `WARN` level or lower: |
| 282 | /// .or(LevelFilter::WARN); |
| 283 | /// |
| 284 | /// tracing_subscriber::registry() |
| 285 | /// .with(tracing_subscriber::fmt::layer().with_filter(filter)) |
| 286 | /// .init(); |
| 287 | /// ``` |
| 288 | /// |
| 289 | /// [`Filter`]: crate::layer::Filter |
| 290 | /// [`and`]: FilterExt::and |
| 291 | fn or<B>(self, other: B) -> combinator::Or<Self, B, S> |
| 292 | where |
| 293 | Self: Sized, |
| 294 | B: layer::Filter<S>, |
| 295 | { |
| 296 | combinator::Or::new(self, other) |
| 297 | } |
| 298 | |
| 299 | /// Inverts `self`, returning a filter that enables spans and events only if |
| 300 | /// `self` would *not* enable them. |
| 301 | /// |
| 302 | /// This inverts the values returned by the [`enabled`] and [`callsite_enabled`] |
| 303 | /// methods on the wrapped filter; it does *not* invert [`event_enabled`], as |
| 304 | /// filters which do not implement filtering on event field values will return |
| 305 | /// the default `true` even for events that their [`enabled`] method disables. |
| 306 | /// |
| 307 | /// Consider a normal filter defined as: |
| 308 | /// |
| 309 | /// ```ignore (pseudo-code) |
| 310 | /// // for spans |
| 311 | /// match callsite_enabled() { |
| 312 | /// ALWAYS => on_span(), |
| 313 | /// SOMETIMES => if enabled() { on_span() }, |
| 314 | /// NEVER => (), |
| 315 | /// } |
| 316 | /// // for events |
| 317 | /// match callsite_enabled() { |
| 318 | /// ALWAYS => on_event(), |
| 319 | /// SOMETIMES => if enabled() && event_enabled() { on_event() }, |
| 320 | /// NEVER => (), |
| 321 | /// } |
| 322 | /// ``` |
| 323 | /// |
| 324 | /// and an inverted filter defined as: |
| 325 | /// |
| 326 | /// ```ignore (pseudo-code) |
| 327 | /// // for spans |
| 328 | /// match callsite_enabled() { |
| 329 | /// ALWAYS => (), |
| 330 | /// SOMETIMES => if !enabled() { on_span() }, |
| 331 | /// NEVER => on_span(), |
| 332 | /// } |
| 333 | /// // for events |
| 334 | /// match callsite_enabled() { |
| 335 | /// ALWAYS => (), |
| 336 | /// SOMETIMES => if !enabled() { on_event() }, |
| 337 | /// NEVER => on_event(), |
| 338 | /// } |
| 339 | /// ``` |
| 340 | /// |
| 341 | /// A proper inversion would do `!(enabled() && event_enabled())` (or |
| 342 | /// `!enabled() || !event_enabled()`), but because of the implicit `&&` |
| 343 | /// relation between `enabled` and `event_enabled`, it is difficult to |
| 344 | /// short circuit and not call the wrapped `event_enabled`. |
| 345 | /// |
| 346 | /// A combinator which remembers the result of `enabled` in order to call |
| 347 | /// `event_enabled` only when `enabled() == true` is possible, but requires |
| 348 | /// additional thread-local mutable state to support a very niche use case. |
| 349 | // |
| 350 | // Also, it'd mean the wrapped layer's `enabled()` always gets called and |
| 351 | // globally applied to events where it doesn't today, since we can't know |
| 352 | // what `event_enabled` will say until we have the event to call it with. |
| 353 | /// |
| 354 | /// [`Filter`]: crate::layer::Filter |
| 355 | /// [`enabled`]: crate::layer::Filter::enabled |
| 356 | /// [`event_enabled`]: crate::layer::Filter::event_enabled |
| 357 | /// [`callsite_enabled`]: crate::layer::Filter::callsite_enabled |
| 358 | fn not(self) -> combinator::Not<Self, S> |
| 359 | where |
| 360 | Self: Sized, |
| 361 | { |
| 362 | combinator::Not::new(self) |
| 363 | } |
| 364 | |
| 365 | /// [Boxes] `self`, erasing its concrete type. |
| 366 | /// |
| 367 | /// This is equivalent to calling [`Box::new`], but in method form, so that |
| 368 | /// it can be used when chaining combinator methods. |
| 369 | /// |
| 370 | /// # Examples |
| 371 | /// |
| 372 | /// When different combinations of filters are used conditionally, they may |
| 373 | /// have different types. For example, the following code won't compile, |
| 374 | /// since the `if` and `else` clause produce filters of different types: |
| 375 | /// |
| 376 | /// ```compile_fail |
| 377 | /// use tracing_subscriber::{ |
| 378 | /// filter::{filter_fn, LevelFilter, FilterExt}, |
| 379 | /// prelude::*, |
| 380 | /// }; |
| 381 | /// |
| 382 | /// let enable_bar_target: bool = // ... |
| 383 | /// # false; |
| 384 | /// |
| 385 | /// let filter = if enable_bar_target { |
| 386 | /// filter_fn(|meta| meta.target().starts_with("foo" )) |
| 387 | /// // If `enable_bar_target` is true, add a `filter_fn` enabling |
| 388 | /// // spans and events with the target `bar`: |
| 389 | /// .or(filter_fn(|meta| meta.target().starts_with("bar" ))) |
| 390 | /// .and(LevelFilter::INFO) |
| 391 | /// } else { |
| 392 | /// filter_fn(|meta| meta.target().starts_with("foo" )) |
| 393 | /// .and(LevelFilter::INFO) |
| 394 | /// }; |
| 395 | /// |
| 396 | /// tracing_subscriber::registry() |
| 397 | /// .with(tracing_subscriber::fmt::layer().with_filter(filter)) |
| 398 | /// .init(); |
| 399 | /// ``` |
| 400 | /// |
| 401 | /// By using `boxed`, the types of the two different branches can be erased, |
| 402 | /// so the assignment to the `filter` variable is valid (as both branches |
| 403 | /// have the type `Box<dyn Filter<S> + Send + Sync + 'static>`). The |
| 404 | /// following code *does* compile: |
| 405 | /// |
| 406 | /// ``` |
| 407 | /// use tracing_subscriber::{ |
| 408 | /// filter::{filter_fn, LevelFilter, FilterExt}, |
| 409 | /// prelude::*, |
| 410 | /// }; |
| 411 | /// |
| 412 | /// let enable_bar_target: bool = // ... |
| 413 | /// # false; |
| 414 | /// |
| 415 | /// let filter = if enable_bar_target { |
| 416 | /// filter_fn(|meta| meta.target().starts_with("foo" )) |
| 417 | /// .or(filter_fn(|meta| meta.target().starts_with("bar" ))) |
| 418 | /// .and(LevelFilter::INFO) |
| 419 | /// // Boxing the filter erases its type, so both branches now |
| 420 | /// // have the same type. |
| 421 | /// .boxed() |
| 422 | /// } else { |
| 423 | /// filter_fn(|meta| meta.target().starts_with("foo" )) |
| 424 | /// .and(LevelFilter::INFO) |
| 425 | /// .boxed() |
| 426 | /// }; |
| 427 | /// |
| 428 | /// tracing_subscriber::registry() |
| 429 | /// .with(tracing_subscriber::fmt::layer().with_filter(filter)) |
| 430 | /// .init(); |
| 431 | /// ``` |
| 432 | /// |
| 433 | /// [Boxes]: std::boxed |
| 434 | /// [`Box::new`]: std::boxed::Box::new |
| 435 | fn boxed(self) -> Box<dyn layer::Filter<S> + Send + Sync + 'static> |
| 436 | where |
| 437 | Self: Sized + Send + Sync + 'static, |
| 438 | { |
| 439 | Box::new(self) |
| 440 | } |
| 441 | } |
| 442 | |
| 443 | // === impl Filter === |
| 444 | |
| 445 | #[cfg (feature = "registry" )] |
| 446 | #[cfg_attr (docsrs, doc(cfg(feature = "registry" )))] |
| 447 | impl<S> layer::Filter<S> for LevelFilter { |
| 448 | fn enabled(&self, meta: &Metadata<'_>, _: &Context<'_, S>) -> bool { |
| 449 | meta.level() <= self |
| 450 | } |
| 451 | |
| 452 | fn callsite_enabled(&self, meta: &'static Metadata<'static>) -> Interest { |
| 453 | if meta.level() <= self { |
| 454 | Interest::always() |
| 455 | } else { |
| 456 | Interest::never() |
| 457 | } |
| 458 | } |
| 459 | |
| 460 | fn max_level_hint(&self) -> Option<LevelFilter> { |
| 461 | Some(*self) |
| 462 | } |
| 463 | } |
| 464 | |
| 465 | macro_rules! filter_impl_body { |
| 466 | () => { |
| 467 | #[inline] |
| 468 | fn enabled(&self, meta: &Metadata<'_>, cx: &Context<'_, S>) -> bool { |
| 469 | self.deref().enabled(meta, cx) |
| 470 | } |
| 471 | |
| 472 | #[inline] |
| 473 | fn callsite_enabled(&self, meta: &'static Metadata<'static>) -> Interest { |
| 474 | self.deref().callsite_enabled(meta) |
| 475 | } |
| 476 | |
| 477 | #[inline] |
| 478 | fn max_level_hint(&self) -> Option<LevelFilter> { |
| 479 | self.deref().max_level_hint() |
| 480 | } |
| 481 | |
| 482 | #[inline] |
| 483 | fn event_enabled(&self, event: &Event<'_>, cx: &Context<'_, S>) -> bool { |
| 484 | self.deref().event_enabled(event, cx) |
| 485 | } |
| 486 | |
| 487 | #[inline] |
| 488 | fn on_new_span(&self, attrs: &span::Attributes<'_>, id: &span::Id, ctx: Context<'_, S>) { |
| 489 | self.deref().on_new_span(attrs, id, ctx) |
| 490 | } |
| 491 | |
| 492 | #[inline] |
| 493 | fn on_record(&self, id: &span::Id, values: &span::Record<'_>, ctx: Context<'_, S>) { |
| 494 | self.deref().on_record(id, values, ctx) |
| 495 | } |
| 496 | |
| 497 | #[inline] |
| 498 | fn on_enter(&self, id: &span::Id, ctx: Context<'_, S>) { |
| 499 | self.deref().on_enter(id, ctx) |
| 500 | } |
| 501 | |
| 502 | #[inline] |
| 503 | fn on_exit(&self, id: &span::Id, ctx: Context<'_, S>) { |
| 504 | self.deref().on_exit(id, ctx) |
| 505 | } |
| 506 | |
| 507 | #[inline] |
| 508 | fn on_close(&self, id: span::Id, ctx: Context<'_, S>) { |
| 509 | self.deref().on_close(id, ctx) |
| 510 | } |
| 511 | }; |
| 512 | } |
| 513 | |
| 514 | #[cfg (feature = "registry" )] |
| 515 | #[cfg_attr (docsrs, doc(cfg(feature = "registry" )))] |
| 516 | impl<S> layer::Filter<S> for Arc<dyn layer::Filter<S> + Send + Sync + 'static> { |
| 517 | filter_impl_body!(); |
| 518 | } |
| 519 | |
| 520 | #[cfg (feature = "registry" )] |
| 521 | #[cfg_attr (docsrs, doc(cfg(feature = "registry" )))] |
| 522 | impl<S> layer::Filter<S> for Box<dyn layer::Filter<S> + Send + Sync + 'static> { |
| 523 | filter_impl_body!(); |
| 524 | } |
| 525 | |
| 526 | // Implement Filter for Option<Filter> where None => allow |
| 527 | #[cfg (feature = "registry" )] |
| 528 | #[cfg_attr (docsrs, doc(cfg(feature = "registry" )))] |
| 529 | impl<F, S> layer::Filter<S> for Option<F> |
| 530 | where |
| 531 | F: layer::Filter<S>, |
| 532 | { |
| 533 | #[inline ] |
| 534 | fn enabled(&self, meta: &Metadata<'_>, ctx: &Context<'_, S>) -> bool { |
| 535 | self.as_ref() |
| 536 | .map(|inner| inner.enabled(meta, ctx)) |
| 537 | .unwrap_or(true) |
| 538 | } |
| 539 | |
| 540 | #[inline ] |
| 541 | fn callsite_enabled(&self, meta: &'static Metadata<'static>) -> Interest { |
| 542 | self.as_ref() |
| 543 | .map(|inner| inner.callsite_enabled(meta)) |
| 544 | .unwrap_or_else(Interest::always) |
| 545 | } |
| 546 | |
| 547 | #[inline ] |
| 548 | fn max_level_hint(&self) -> Option<LevelFilter> { |
| 549 | self.as_ref().and_then(|inner| inner.max_level_hint()) |
| 550 | } |
| 551 | |
| 552 | #[inline ] |
| 553 | fn event_enabled(&self, event: &Event<'_>, ctx: &Context<'_, S>) -> bool { |
| 554 | self.as_ref() |
| 555 | .map(|inner| inner.event_enabled(event, ctx)) |
| 556 | .unwrap_or(true) |
| 557 | } |
| 558 | |
| 559 | #[inline ] |
| 560 | fn on_new_span(&self, attrs: &span::Attributes<'_>, id: &span::Id, ctx: Context<'_, S>) { |
| 561 | if let Some(inner) = self { |
| 562 | inner.on_new_span(attrs, id, ctx) |
| 563 | } |
| 564 | } |
| 565 | |
| 566 | #[inline ] |
| 567 | fn on_record(&self, id: &span::Id, values: &span::Record<'_>, ctx: Context<'_, S>) { |
| 568 | if let Some(inner) = self { |
| 569 | inner.on_record(id, values, ctx) |
| 570 | } |
| 571 | } |
| 572 | |
| 573 | #[inline ] |
| 574 | fn on_enter(&self, id: &span::Id, ctx: Context<'_, S>) { |
| 575 | if let Some(inner) = self { |
| 576 | inner.on_enter(id, ctx) |
| 577 | } |
| 578 | } |
| 579 | |
| 580 | #[inline ] |
| 581 | fn on_exit(&self, id: &span::Id, ctx: Context<'_, S>) { |
| 582 | if let Some(inner) = self { |
| 583 | inner.on_exit(id, ctx) |
| 584 | } |
| 585 | } |
| 586 | |
| 587 | #[inline ] |
| 588 | fn on_close(&self, id: span::Id, ctx: Context<'_, S>) { |
| 589 | if let Some(inner) = self { |
| 590 | inner.on_close(id, ctx) |
| 591 | } |
| 592 | } |
| 593 | } |
| 594 | |
| 595 | // === impl Filtered === |
| 596 | |
| 597 | impl<L, F, S> Filtered<L, F, S> { |
| 598 | /// Wraps the provided [`Layer`] so that it is filtered by the given |
| 599 | /// [`Filter`]. |
| 600 | /// |
| 601 | /// This is equivalent to calling the [`Layer::with_filter`] method. |
| 602 | /// |
| 603 | /// See the [documentation on per-layer filtering][plf] for details. |
| 604 | /// |
| 605 | /// [`Filter`]: crate::layer::Filter |
| 606 | /// [plf]: crate::layer#per-layer-filtering |
| 607 | pub fn new(layer: L, filter: F) -> Self { |
| 608 | Self { |
| 609 | layer, |
| 610 | filter, |
| 611 | id: MagicPlfDowncastMarker(FilterId::disabled()), |
| 612 | _s: PhantomData, |
| 613 | } |
| 614 | } |
| 615 | |
| 616 | #[inline (always)] |
| 617 | fn id(&self) -> FilterId { |
| 618 | debug_assert!( |
| 619 | !self.id.0.is_disabled(), |
| 620 | "a `Filtered` layer was used, but it had no `FilterId`; \ |
| 621 | was it registered with the subscriber?" |
| 622 | ); |
| 623 | self.id.0 |
| 624 | } |
| 625 | |
| 626 | fn did_enable(&self, f: impl FnOnce()) { |
| 627 | FILTERING.with(|filtering| filtering.did_enable(self.id(), f)) |
| 628 | } |
| 629 | |
| 630 | /// Borrows the [`Filter`](crate::layer::Filter) used by this layer. |
| 631 | pub fn filter(&self) -> &F { |
| 632 | &self.filter |
| 633 | } |
| 634 | |
| 635 | /// Mutably borrows the [`Filter`](crate::layer::Filter) used by this layer. |
| 636 | /// |
| 637 | /// When this layer can be mutably borrowed, this may be used to mutate the filter. |
| 638 | /// Generally, this will primarily be used with the |
| 639 | /// [`reload::Handle::modify`](crate::reload::Handle::modify) method. |
| 640 | /// |
| 641 | /// # Examples |
| 642 | /// |
| 643 | /// ``` |
| 644 | /// # use tracing::info; |
| 645 | /// # use tracing_subscriber::{filter,fmt,reload,Registry,prelude::*}; |
| 646 | /// # fn main() { |
| 647 | /// let filtered_layer = fmt::Layer::default().with_filter(filter::LevelFilter::WARN); |
| 648 | /// let (filtered_layer, reload_handle) = reload::Layer::new(filtered_layer); |
| 649 | /// # |
| 650 | /// # // specifying the Registry type is required |
| 651 | /// # let _: &reload::Handle<filter::Filtered<fmt::Layer<Registry>, |
| 652 | /// # filter::LevelFilter, Registry>,Registry> |
| 653 | /// # = &reload_handle; |
| 654 | /// # |
| 655 | /// info!("This will be ignored" ); |
| 656 | /// reload_handle.modify(|layer| *layer.filter_mut() = filter::LevelFilter::INFO); |
| 657 | /// info!("This will be logged" ); |
| 658 | /// # } |
| 659 | /// ``` |
| 660 | pub fn filter_mut(&mut self) -> &mut F { |
| 661 | &mut self.filter |
| 662 | } |
| 663 | |
| 664 | /// Borrows the inner [`Layer`] wrapped by this `Filtered` layer. |
| 665 | pub fn inner(&self) -> &L { |
| 666 | &self.layer |
| 667 | } |
| 668 | |
| 669 | /// Mutably borrows the inner [`Layer`] wrapped by this `Filtered` layer. |
| 670 | /// |
| 671 | /// This method is primarily expected to be used with the |
| 672 | /// [`reload::Handle::modify`](crate::reload::Handle::modify) method. |
| 673 | /// |
| 674 | /// # Examples |
| 675 | /// |
| 676 | /// ``` |
| 677 | /// # use tracing::info; |
| 678 | /// # use tracing_subscriber::{filter,fmt,reload,Registry,prelude::*}; |
| 679 | /// # fn non_blocking<T: std::io::Write>(writer: T) -> (fn() -> std::io::Stdout) { |
| 680 | /// # std::io::stdout |
| 681 | /// # } |
| 682 | /// # fn main() { |
| 683 | /// let filtered_layer = fmt::layer().with_writer(non_blocking(std::io::stderr())).with_filter(filter::LevelFilter::INFO); |
| 684 | /// let (filtered_layer, reload_handle) = reload::Layer::new(filtered_layer); |
| 685 | /// # |
| 686 | /// # // specifying the Registry type is required |
| 687 | /// # let _: &reload::Handle<filter::Filtered<fmt::Layer<Registry, _, _, fn() -> std::io::Stdout>, |
| 688 | /// # filter::LevelFilter, Registry>, Registry> |
| 689 | /// # = &reload_handle; |
| 690 | /// # |
| 691 | /// info!("This will be logged to stderr" ); |
| 692 | /// reload_handle.modify(|layer| *layer.inner_mut().writer_mut() = non_blocking(std::io::stdout())); |
| 693 | /// info!("This will be logged to stdout" ); |
| 694 | /// # } |
| 695 | /// ``` |
| 696 | /// |
| 697 | /// [`Layer`]: crate::layer::Layer |
| 698 | pub fn inner_mut(&mut self) -> &mut L { |
| 699 | &mut self.layer |
| 700 | } |
| 701 | } |
| 702 | |
| 703 | impl<S, L, F> Layer<S> for Filtered<L, F, S> |
| 704 | where |
| 705 | S: Subscriber + for<'span> registry::LookupSpan<'span> + 'static, |
| 706 | F: layer::Filter<S> + 'static, |
| 707 | L: Layer<S>, |
| 708 | { |
| 709 | fn on_register_dispatch(&self, subscriber: &Dispatch) { |
| 710 | self.layer.on_register_dispatch(subscriber); |
| 711 | } |
| 712 | |
| 713 | fn on_layer(&mut self, subscriber: &mut S) { |
| 714 | self.id = MagicPlfDowncastMarker(subscriber.register_filter()); |
| 715 | self.layer.on_layer(subscriber); |
| 716 | } |
| 717 | |
| 718 | // TODO(eliza): can we figure out a nice way to make the `Filtered` layer |
| 719 | // not call `is_enabled_for` in hooks that the inner layer doesn't actually |
| 720 | // have real implementations of? probably not... |
| 721 | // |
| 722 | // it would be cool if there was some wild rust reflection way of checking |
| 723 | // if a trait impl has the default impl of a trait method or not, but that's |
| 724 | // almsot certainly impossible...right? |
| 725 | |
| 726 | fn register_callsite(&self, metadata: &'static Metadata<'static>) -> Interest { |
| 727 | let interest = self.filter.callsite_enabled(metadata); |
| 728 | |
| 729 | // If the filter didn't disable the callsite, allow the inner layer to |
| 730 | // register it — since `register_callsite` is also used for purposes |
| 731 | // such as reserving/caching per-callsite data, we want the inner layer |
| 732 | // to be able to perform any other registration steps. However, we'll |
| 733 | // ignore its `Interest`. |
| 734 | if !interest.is_never() { |
| 735 | self.layer.register_callsite(metadata); |
| 736 | } |
| 737 | |
| 738 | // Add our `Interest` to the current sum of per-layer filter `Interest`s |
| 739 | // for this callsite. |
| 740 | FILTERING.with(|filtering| filtering.add_interest(interest)); |
| 741 | |
| 742 | // don't short circuit! if the stack consists entirely of `Layer`s with |
| 743 | // per-layer filters, the `Registry` will return the actual `Interest` |
| 744 | // value that's the sum of all the `register_callsite` calls to those |
| 745 | // per-layer filters. if we returned an actual `never` interest here, a |
| 746 | // `Layered` layer would short-circuit and not allow any `Filtered` |
| 747 | // layers below us if _they_ are interested in the callsite. |
| 748 | Interest::always() |
| 749 | } |
| 750 | |
| 751 | fn enabled(&self, metadata: &Metadata<'_>, cx: Context<'_, S>) -> bool { |
| 752 | let cx = cx.with_filter(self.id()); |
| 753 | let enabled = self.filter.enabled(metadata, &cx); |
| 754 | FILTERING.with(|filtering| filtering.set(self.id(), enabled)); |
| 755 | |
| 756 | if enabled { |
| 757 | // If the filter enabled this metadata, ask the wrapped layer if |
| 758 | // _it_ wants it --- it might have a global filter. |
| 759 | self.layer.enabled(metadata, cx) |
| 760 | } else { |
| 761 | // Otherwise, return `true`. The _per-layer_ filter disabled this |
| 762 | // metadata, but returning `false` in `Layer::enabled` will |
| 763 | // short-circuit and globally disable the span or event. This is |
| 764 | // *not* what we want for per-layer filters, as other layers may |
| 765 | // still want this event. Returning `true` here means we'll continue |
| 766 | // asking the next layer in the stack. |
| 767 | // |
| 768 | // Once all per-layer filters have been evaluated, the `Registry` |
| 769 | // at the root of the stack will return `false` from its `enabled` |
| 770 | // method if *every* per-layer filter disabled this metadata. |
| 771 | // Otherwise, the individual per-layer filters will skip the next |
| 772 | // `new_span` or `on_event` call for their layer if *they* disabled |
| 773 | // the span or event, but it was not globally disabled. |
| 774 | true |
| 775 | } |
| 776 | } |
| 777 | |
| 778 | fn on_new_span(&self, attrs: &span::Attributes<'_>, id: &span::Id, cx: Context<'_, S>) { |
| 779 | self.did_enable(|| { |
| 780 | let cx = cx.with_filter(self.id()); |
| 781 | self.filter.on_new_span(attrs, id, cx.clone()); |
| 782 | self.layer.on_new_span(attrs, id, cx); |
| 783 | }) |
| 784 | } |
| 785 | |
| 786 | #[doc (hidden)] |
| 787 | fn max_level_hint(&self) -> Option<LevelFilter> { |
| 788 | self.filter.max_level_hint() |
| 789 | } |
| 790 | |
| 791 | fn on_record(&self, span: &span::Id, values: &span::Record<'_>, cx: Context<'_, S>) { |
| 792 | if let Some(cx) = cx.if_enabled_for(span, self.id()) { |
| 793 | self.filter.on_record(span, values, cx.clone()); |
| 794 | self.layer.on_record(span, values, cx) |
| 795 | } |
| 796 | } |
| 797 | |
| 798 | fn on_follows_from(&self, span: &span::Id, follows: &span::Id, cx: Context<'_, S>) { |
| 799 | // only call `on_follows_from` if both spans are enabled by us |
| 800 | if cx.is_enabled_for(span, self.id()) && cx.is_enabled_for(follows, self.id()) { |
| 801 | self.layer |
| 802 | .on_follows_from(span, follows, cx.with_filter(self.id())) |
| 803 | } |
| 804 | } |
| 805 | |
| 806 | fn event_enabled(&self, event: &Event<'_>, cx: Context<'_, S>) -> bool { |
| 807 | let cx = cx.with_filter(self.id()); |
| 808 | let enabled = FILTERING |
| 809 | .with(|filtering| filtering.and(self.id(), || self.filter.event_enabled(event, &cx))); |
| 810 | |
| 811 | if enabled { |
| 812 | // If the filter enabled this event, ask the wrapped subscriber if |
| 813 | // _it_ wants it --- it might have a global filter. |
| 814 | self.layer.event_enabled(event, cx) |
| 815 | } else { |
| 816 | // Otherwise, return `true`. See the comment in `enabled` for why this |
| 817 | // is necessary. |
| 818 | true |
| 819 | } |
| 820 | } |
| 821 | |
| 822 | fn on_event(&self, event: &Event<'_>, cx: Context<'_, S>) { |
| 823 | self.did_enable(|| { |
| 824 | self.layer.on_event(event, cx.with_filter(self.id())); |
| 825 | }) |
| 826 | } |
| 827 | |
| 828 | fn on_enter(&self, id: &span::Id, cx: Context<'_, S>) { |
| 829 | if let Some(cx) = cx.if_enabled_for(id, self.id()) { |
| 830 | self.filter.on_enter(id, cx.clone()); |
| 831 | self.layer.on_enter(id, cx); |
| 832 | } |
| 833 | } |
| 834 | |
| 835 | fn on_exit(&self, id: &span::Id, cx: Context<'_, S>) { |
| 836 | if let Some(cx) = cx.if_enabled_for(id, self.id()) { |
| 837 | self.filter.on_exit(id, cx.clone()); |
| 838 | self.layer.on_exit(id, cx); |
| 839 | } |
| 840 | } |
| 841 | |
| 842 | fn on_close(&self, id: span::Id, cx: Context<'_, S>) { |
| 843 | if let Some(cx) = cx.if_enabled_for(&id, self.id()) { |
| 844 | self.filter.on_close(id.clone(), cx.clone()); |
| 845 | self.layer.on_close(id, cx); |
| 846 | } |
| 847 | } |
| 848 | |
| 849 | // XXX(eliza): the existence of this method still makes me sad... |
| 850 | fn on_id_change(&self, old: &span::Id, new: &span::Id, cx: Context<'_, S>) { |
| 851 | if let Some(cx) = cx.if_enabled_for(old, self.id()) { |
| 852 | self.layer.on_id_change(old, new, cx) |
| 853 | } |
| 854 | } |
| 855 | |
| 856 | #[doc (hidden)] |
| 857 | #[inline ] |
| 858 | unsafe fn downcast_raw(&self, id: TypeId) -> Option<*const ()> { |
| 859 | match id { |
| 860 | id if id == TypeId::of::<Self>() => Some(self as *const _ as *const ()), |
| 861 | id if id == TypeId::of::<L>() => Some(&self.layer as *const _ as *const ()), |
| 862 | id if id == TypeId::of::<F>() => Some(&self.filter as *const _ as *const ()), |
| 863 | id if id == TypeId::of::<MagicPlfDowncastMarker>() => { |
| 864 | Some(&self.id as *const _ as *const ()) |
| 865 | } |
| 866 | _ => self.layer.downcast_raw(id), |
| 867 | } |
| 868 | } |
| 869 | } |
| 870 | |
| 871 | impl<F, L, S> fmt::Debug for Filtered<F, L, S> |
| 872 | where |
| 873 | F: fmt::Debug, |
| 874 | L: fmt::Debug, |
| 875 | { |
| 876 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 877 | f.debug_struct("Filtered" ) |
| 878 | .field("filter" , &self.filter) |
| 879 | .field("layer" , &self.layer) |
| 880 | .field("id" , &self.id) |
| 881 | .finish() |
| 882 | } |
| 883 | } |
| 884 | |
| 885 | // === impl FilterId === |
| 886 | |
| 887 | impl FilterId { |
| 888 | const fn disabled() -> Self { |
| 889 | Self(std::u64::MAX) |
| 890 | } |
| 891 | |
| 892 | /// Returns a `FilterId` that will consider _all_ spans enabled. |
| 893 | pub(crate) const fn none() -> Self { |
| 894 | Self(0) |
| 895 | } |
| 896 | |
| 897 | pub(crate) fn new(id: u8) -> Self { |
| 898 | assert!(id < 64, "filter IDs may not be greater than 64" ); |
| 899 | Self(1 << id as usize) |
| 900 | } |
| 901 | |
| 902 | /// Combines two `FilterId`s, returning a new `FilterId` that will match a |
| 903 | /// [`FilterMap`] where the span was disabled by _either_ this `FilterId` |
| 904 | /// *or* the combined `FilterId`. |
| 905 | /// |
| 906 | /// This method is called by [`Context`]s when adding the `FilterId` of a |
| 907 | /// [`Filtered`] layer to the context. |
| 908 | /// |
| 909 | /// This is necessary for cases where we have a tree of nested [`Filtered`] |
| 910 | /// layers, like this: |
| 911 | /// |
| 912 | /// ```text |
| 913 | /// Filtered { |
| 914 | /// filter1, |
| 915 | /// Layered { |
| 916 | /// layer1, |
| 917 | /// Filtered { |
| 918 | /// filter2, |
| 919 | /// layer2, |
| 920 | /// }, |
| 921 | /// } |
| 922 | /// ``` |
| 923 | /// |
| 924 | /// We want `layer2` to be affected by both `filter1` _and_ `filter2`. |
| 925 | /// Without combining `FilterId`s, this works fine when filtering |
| 926 | /// `on_event`/`new_span`, because the outer `Filtered` layer (`filter1`) |
| 927 | /// won't call the inner layer's `on_event` or `new_span` callbacks if it |
| 928 | /// disabled the event/span. |
| 929 | /// |
| 930 | /// However, it _doesn't_ work when filtering span lookups and traversals |
| 931 | /// (e.g. `scope`). This is because the [`Context`] passed to `layer2` |
| 932 | /// would set its filter ID to the filter ID of `filter2`, and would skip |
| 933 | /// spans that were disabled by `filter2`. However, what if a span was |
| 934 | /// disabled by `filter1`? We wouldn't see it in `new_span`, but we _would_ |
| 935 | /// see it in lookups and traversals...which we don't want. |
| 936 | /// |
| 937 | /// When a [`Filtered`] layer adds its ID to a [`Context`], it _combines_ it |
| 938 | /// with any previous filter ID that the context had, rather than replacing |
| 939 | /// it. That way, `layer2`'s context will check if a span was disabled by |
| 940 | /// `filter1` _or_ `filter2`. The way we do this, instead of representing |
| 941 | /// `FilterId`s as a number number that we shift a 1 over by to get a mask, |
| 942 | /// we just store the actual mask,so we can combine them with a bitwise-OR. |
| 943 | /// |
| 944 | /// For example, if we consider the following case (pretending that the |
| 945 | /// masks are 8 bits instead of 64 just so i don't have to write out a bunch |
| 946 | /// of extra zeroes): |
| 947 | /// |
| 948 | /// - `filter1` has the filter id 1 (`0b0000_0001`) |
| 949 | /// - `filter2` has the filter id 2 (`0b0000_0010`) |
| 950 | /// |
| 951 | /// A span that gets disabled by filter 1 would have the [`FilterMap`] with |
| 952 | /// bits `0b0000_0001`. |
| 953 | /// |
| 954 | /// If the `FilterId` was internally represented as `(bits to shift + 1), |
| 955 | /// when `layer2`'s [`Context`] checked if it enabled the span, it would |
| 956 | /// make the mask `0b0000_0010` (`1 << 1`). That bit would not be set in the |
| 957 | /// [`FilterMap`], so it would see that it _didn't_ disable the span. Which |
| 958 | /// is *true*, it just doesn't reflect the tree-like shape of the actual |
| 959 | /// subscriber. |
| 960 | /// |
| 961 | /// By having the IDs be masks instead of shifts, though, when the |
| 962 | /// [`Filtered`] with `filter2` gets the [`Context`] with `filter1`'s filter ID, |
| 963 | /// instead of replacing it, it ors them together: |
| 964 | /// |
| 965 | /// ```ignore |
| 966 | /// 0b0000_0001 | 0b0000_0010 == 0b0000_0011; |
| 967 | /// ``` |
| 968 | /// |
| 969 | /// We then test if the span was disabled by seeing if _any_ bits in the |
| 970 | /// mask are `1`: |
| 971 | /// |
| 972 | /// ```ignore |
| 973 | /// filtermap & mask != 0; |
| 974 | /// 0b0000_0001 & 0b0000_0011 != 0; |
| 975 | /// 0b0000_0001 != 0; |
| 976 | /// true; |
| 977 | /// ``` |
| 978 | /// |
| 979 | /// [`Context`]: crate::layer::Context |
| 980 | pub(crate) fn and(self, FilterId(other): Self) -> Self { |
| 981 | // If this mask is disabled, just return the other --- otherwise, we |
| 982 | // would always see that every span is disabled. |
| 983 | if self.0 == Self::disabled().0 { |
| 984 | return Self(other); |
| 985 | } |
| 986 | |
| 987 | Self(self.0 | other) |
| 988 | } |
| 989 | |
| 990 | fn is_disabled(self) -> bool { |
| 991 | self.0 == Self::disabled().0 |
| 992 | } |
| 993 | } |
| 994 | |
| 995 | impl fmt::Debug for FilterId { |
| 996 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 997 | // don't print a giant set of the numbers 0..63 if the filter ID is disabled. |
| 998 | if self.0 == Self::disabled().0 { |
| 999 | return f |
| 1000 | .debug_tuple("FilterId" ) |
| 1001 | .field(&format_args!("DISABLED" )) |
| 1002 | .finish(); |
| 1003 | } |
| 1004 | |
| 1005 | if f.alternate() { |
| 1006 | f.debug_struct("FilterId" ) |
| 1007 | .field("ids" , &format_args!("{:?}" , FmtBitset(self.0))) |
| 1008 | .field("bits" , &format_args!("{:b}" , self.0)) |
| 1009 | .finish() |
| 1010 | } else { |
| 1011 | f.debug_tuple("FilterId" ).field(&FmtBitset(self.0)).finish() |
| 1012 | } |
| 1013 | } |
| 1014 | } |
| 1015 | |
| 1016 | impl fmt::Binary for FilterId { |
| 1017 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 1018 | f.debug_tuple("FilterId" ) |
| 1019 | .field(&format_args!("{:b}" , self.0)) |
| 1020 | .finish() |
| 1021 | } |
| 1022 | } |
| 1023 | |
| 1024 | // === impl FilterExt === |
| 1025 | |
| 1026 | impl<F, S> FilterExt<S> for F where F: layer::Filter<S> {} |
| 1027 | |
| 1028 | // === impl FilterMap === |
| 1029 | |
| 1030 | impl FilterMap { |
| 1031 | pub(crate) fn set(self, FilterId(mask): FilterId, enabled: bool) -> Self { |
| 1032 | if mask == std::u64::MAX { |
| 1033 | return self; |
| 1034 | } |
| 1035 | |
| 1036 | if enabled { |
| 1037 | Self { |
| 1038 | bits: self.bits & (!mask), |
| 1039 | } |
| 1040 | } else { |
| 1041 | Self { |
| 1042 | bits: self.bits | mask, |
| 1043 | } |
| 1044 | } |
| 1045 | } |
| 1046 | |
| 1047 | #[inline ] |
| 1048 | pub(crate) fn is_enabled(self, FilterId(mask): FilterId) -> bool { |
| 1049 | self.bits & mask == 0 |
| 1050 | } |
| 1051 | |
| 1052 | #[inline ] |
| 1053 | pub(crate) fn any_enabled(self) -> bool { |
| 1054 | self.bits != std::u64::MAX |
| 1055 | } |
| 1056 | } |
| 1057 | |
| 1058 | impl fmt::Debug for FilterMap { |
| 1059 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 1060 | let alt = f.alternate(); |
| 1061 | let mut s = f.debug_struct("FilterMap" ); |
| 1062 | s.field("disabled_by" , &format_args!("{:?}" , &FmtBitset(self.bits))); |
| 1063 | |
| 1064 | if alt { |
| 1065 | s.field("bits" , &format_args!("{:b}" , self.bits)); |
| 1066 | } |
| 1067 | |
| 1068 | s.finish() |
| 1069 | } |
| 1070 | } |
| 1071 | |
| 1072 | impl fmt::Binary for FilterMap { |
| 1073 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 1074 | f.debug_struct("FilterMap" ) |
| 1075 | .field("bits" , &format_args!("{:b}" , self.bits)) |
| 1076 | .finish() |
| 1077 | } |
| 1078 | } |
| 1079 | |
| 1080 | // === impl FilterState === |
| 1081 | |
| 1082 | impl FilterState { |
| 1083 | fn new() -> Self { |
| 1084 | Self { |
| 1085 | enabled: Cell::new(FilterMap::default()), |
| 1086 | interest: RefCell::new(None), |
| 1087 | |
| 1088 | #[cfg (debug_assertions)] |
| 1089 | counters: DebugCounters::default(), |
| 1090 | } |
| 1091 | } |
| 1092 | |
| 1093 | fn set(&self, filter: FilterId, enabled: bool) { |
| 1094 | #[cfg (debug_assertions)] |
| 1095 | { |
| 1096 | let in_current_pass = self.counters.in_filter_pass.get(); |
| 1097 | if in_current_pass == 0 { |
| 1098 | debug_assert_eq!(self.enabled.get(), FilterMap::default()); |
| 1099 | } |
| 1100 | self.counters.in_filter_pass.set(in_current_pass + 1); |
| 1101 | debug_assert_eq!( |
| 1102 | self.counters.in_interest_pass.get(), |
| 1103 | 0, |
| 1104 | "if we are in or starting a filter pass, we must not be in an interest pass." |
| 1105 | ) |
| 1106 | } |
| 1107 | |
| 1108 | self.enabled.set(self.enabled.get().set(filter, enabled)) |
| 1109 | } |
| 1110 | |
| 1111 | fn add_interest(&self, interest: Interest) { |
| 1112 | let mut curr_interest = self.interest.borrow_mut(); |
| 1113 | |
| 1114 | #[cfg (debug_assertions)] |
| 1115 | { |
| 1116 | let in_current_pass = self.counters.in_interest_pass.get(); |
| 1117 | if in_current_pass == 0 { |
| 1118 | debug_assert!(curr_interest.is_none()); |
| 1119 | } |
| 1120 | self.counters.in_interest_pass.set(in_current_pass + 1); |
| 1121 | } |
| 1122 | |
| 1123 | if let Some(curr_interest) = curr_interest.as_mut() { |
| 1124 | if (curr_interest.is_always() && !interest.is_always()) |
| 1125 | || (curr_interest.is_never() && !interest.is_never()) |
| 1126 | { |
| 1127 | *curr_interest = Interest::sometimes(); |
| 1128 | } |
| 1129 | // If the two interests are the same, do nothing. If the current |
| 1130 | // interest is `sometimes`, stay sometimes. |
| 1131 | } else { |
| 1132 | *curr_interest = Some(interest); |
| 1133 | } |
| 1134 | } |
| 1135 | |
| 1136 | pub(crate) fn event_enabled() -> bool { |
| 1137 | FILTERING |
| 1138 | .try_with(|this| { |
| 1139 | let enabled = this.enabled.get().any_enabled(); |
| 1140 | #[cfg (debug_assertions)] |
| 1141 | { |
| 1142 | if this.counters.in_filter_pass.get() == 0 { |
| 1143 | debug_assert_eq!(this.enabled.get(), FilterMap::default()); |
| 1144 | } |
| 1145 | |
| 1146 | // Nothing enabled this event, we won't tick back down the |
| 1147 | // counter in `did_enable`. Reset it. |
| 1148 | if !enabled { |
| 1149 | this.counters.in_filter_pass.set(0); |
| 1150 | } |
| 1151 | } |
| 1152 | enabled |
| 1153 | }) |
| 1154 | .unwrap_or(true) |
| 1155 | } |
| 1156 | |
| 1157 | /// Executes a closure if the filter with the provided ID did not disable |
| 1158 | /// the current span/event. |
| 1159 | /// |
| 1160 | /// This is used to implement the `on_event` and `new_span` methods for |
| 1161 | /// `Filtered`. |
| 1162 | fn did_enable(&self, filter: FilterId, f: impl FnOnce()) { |
| 1163 | let map = self.enabled.get(); |
| 1164 | if map.is_enabled(filter) { |
| 1165 | // If the filter didn't disable the current span/event, run the |
| 1166 | // callback. |
| 1167 | f(); |
| 1168 | } else { |
| 1169 | // Otherwise, if this filter _did_ disable the span or event |
| 1170 | // currently being processed, clear its bit from this thread's |
| 1171 | // `FilterState`. The bit has already been "consumed" by skipping |
| 1172 | // this callback, and we need to ensure that the `FilterMap` for |
| 1173 | // this thread is reset when the *next* `enabled` call occurs. |
| 1174 | self.enabled.set(map.set(filter, true)); |
| 1175 | } |
| 1176 | #[cfg (debug_assertions)] |
| 1177 | { |
| 1178 | let in_current_pass = self.counters.in_filter_pass.get(); |
| 1179 | if in_current_pass <= 1 { |
| 1180 | debug_assert_eq!(self.enabled.get(), FilterMap::default()); |
| 1181 | } |
| 1182 | self.counters |
| 1183 | .in_filter_pass |
| 1184 | .set(in_current_pass.saturating_sub(1)); |
| 1185 | debug_assert_eq!( |
| 1186 | self.counters.in_interest_pass.get(), |
| 1187 | 0, |
| 1188 | "if we are in a filter pass, we must not be in an interest pass." |
| 1189 | ) |
| 1190 | } |
| 1191 | } |
| 1192 | |
| 1193 | /// Run a second filtering pass, e.g. for Layer::event_enabled. |
| 1194 | fn and(&self, filter: FilterId, f: impl FnOnce() -> bool) -> bool { |
| 1195 | let map = self.enabled.get(); |
| 1196 | let enabled = map.is_enabled(filter) && f(); |
| 1197 | self.enabled.set(map.set(filter, enabled)); |
| 1198 | enabled |
| 1199 | } |
| 1200 | |
| 1201 | /// Clears the current in-progress filter state. |
| 1202 | /// |
| 1203 | /// This resets the [`FilterMap`] and current [`Interest`] as well as |
| 1204 | /// clearing the debug counters. |
| 1205 | pub(crate) fn clear_enabled() { |
| 1206 | // Drop the `Result` returned by `try_with` --- if we are in the middle |
| 1207 | // a panic and the thread-local has been torn down, that's fine, just |
| 1208 | // ignore it ratehr than panicking. |
| 1209 | let _ = FILTERING.try_with(|filtering| { |
| 1210 | filtering.enabled.set(FilterMap::default()); |
| 1211 | |
| 1212 | #[cfg (debug_assertions)] |
| 1213 | filtering.counters.in_filter_pass.set(0); |
| 1214 | }); |
| 1215 | } |
| 1216 | |
| 1217 | pub(crate) fn take_interest() -> Option<Interest> { |
| 1218 | FILTERING |
| 1219 | .try_with(|filtering| { |
| 1220 | #[cfg (debug_assertions)] |
| 1221 | { |
| 1222 | if filtering.counters.in_interest_pass.get() == 0 { |
| 1223 | debug_assert!(filtering.interest.try_borrow().ok()?.is_none()); |
| 1224 | } |
| 1225 | filtering.counters.in_interest_pass.set(0); |
| 1226 | } |
| 1227 | filtering.interest.try_borrow_mut().ok()?.take() |
| 1228 | }) |
| 1229 | .ok()? |
| 1230 | } |
| 1231 | |
| 1232 | pub(crate) fn filter_map(&self) -> FilterMap { |
| 1233 | let map = self.enabled.get(); |
| 1234 | #[cfg (debug_assertions)] |
| 1235 | { |
| 1236 | if self.counters.in_filter_pass.get() == 0 { |
| 1237 | debug_assert_eq!(map, FilterMap::default()); |
| 1238 | } |
| 1239 | } |
| 1240 | |
| 1241 | map |
| 1242 | } |
| 1243 | } |
| 1244 | /// This is a horrible and bad abuse of the downcasting system to expose |
| 1245 | /// *internally* whether a layer has per-layer filtering, within |
| 1246 | /// `tracing-subscriber`, without exposing a public API for it. |
| 1247 | /// |
| 1248 | /// If a `Layer` has per-layer filtering, it will downcast to a |
| 1249 | /// `MagicPlfDowncastMarker`. Since layers which contain other layers permit |
| 1250 | /// downcasting to recurse to their children, this will do the Right Thing with |
| 1251 | /// layers like Reload, Option, etc. |
| 1252 | /// |
| 1253 | /// Why is this a wrapper around the `FilterId`, you may ask? Because |
| 1254 | /// downcasting works by returning a pointer, and we don't want to risk |
| 1255 | /// introducing UB by constructing pointers that _don't_ point to a valid |
| 1256 | /// instance of the type they claim to be. In this case, we don't _intend_ for |
| 1257 | /// this pointer to be dereferenced, so it would actually be fine to return one |
| 1258 | /// that isn't a valid pointer...but we can't guarantee that the caller won't |
| 1259 | /// (accidentally) dereference it, so it's better to be safe than sorry. We |
| 1260 | /// could, alternatively, add an additional field to the type that's used only |
| 1261 | /// for returning pointers to as as part of the evil downcasting hack, but I |
| 1262 | /// thought it was nicer to just add a `repr(transparent)` wrapper to the |
| 1263 | /// existing `FilterId` field, since it won't make the struct any bigger. |
| 1264 | /// |
| 1265 | /// Don't worry, this isn't on the test. :) |
| 1266 | #[derive(Clone, Copy)] |
| 1267 | #[repr (transparent)] |
| 1268 | struct MagicPlfDowncastMarker(FilterId); |
| 1269 | impl fmt::Debug for MagicPlfDowncastMarker { |
| 1270 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 1271 | // Just pretend that `MagicPlfDowncastMarker` doesn't exist for |
| 1272 | // `fmt::Debug` purposes...if no one *sees* it in their `Debug` output, |
| 1273 | // they don't have to know I thought this code would be a good idea. |
| 1274 | fmt::Debug::fmt(&self.0, f) |
| 1275 | } |
| 1276 | } |
| 1277 | |
| 1278 | pub(crate) fn is_plf_downcast_marker(type_id: TypeId) -> bool { |
| 1279 | type_id == TypeId::of::<MagicPlfDowncastMarker>() |
| 1280 | } |
| 1281 | |
| 1282 | /// Does a type implementing `Subscriber` contain any per-layer filters? |
| 1283 | pub(crate) fn subscriber_has_plf<S>(subscriber: &S) -> bool |
| 1284 | where |
| 1285 | S: Subscriber, |
| 1286 | { |
| 1287 | (subscriber as &dyn Subscriber).is::<MagicPlfDowncastMarker>() |
| 1288 | } |
| 1289 | |
| 1290 | /// Does a type implementing `Layer` contain any per-layer filters? |
| 1291 | pub(crate) fn layer_has_plf<L, S>(layer: &L) -> bool |
| 1292 | where |
| 1293 | L: Layer<S>, |
| 1294 | S: Subscriber, |
| 1295 | { |
| 1296 | unsafe { |
| 1297 | // Safety: we're not actually *doing* anything with this pointer --- we |
| 1298 | // only care about the `Option`, which we're turning into a `bool`. So |
| 1299 | // even if the layer decides to be evil and give us some kind of invalid |
| 1300 | // pointer, we don't ever dereference it, so this is always safe. |
| 1301 | layer.downcast_raw(TypeId::of::<MagicPlfDowncastMarker>()) |
| 1302 | } |
| 1303 | .is_some() |
| 1304 | } |
| 1305 | |
| 1306 | struct FmtBitset(u64); |
| 1307 | |
| 1308 | impl fmt::Debug for FmtBitset { |
| 1309 | fn fmt(&self, f: &mut fmt::Formatter<'_>) -> fmt::Result { |
| 1310 | let mut set = f.debug_set(); |
| 1311 | for bit in 0..64 { |
| 1312 | // if the `bit`-th bit is set, add it to the debug set |
| 1313 | if self.0 & (1 << bit) != 0 { |
| 1314 | set.entry(&bit); |
| 1315 | } |
| 1316 | } |
| 1317 | set.finish() |
| 1318 | } |
| 1319 | } |
| 1320 | |