[INFO] crate tracing 0.1.16 is already in cache
[INFO] testing tracing-0.1.16 against master#8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e for pr-74409
[INFO] extracting crate tracing 0.1.16 into /workspace/builds/worker-12/source
[INFO] validating manifest of crates.io crate tracing 0.1.16 on toolchain 8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] started tweaking crates.io crate tracing 0.1.16
[INFO] finished tweaking crates.io crate tracing 0.1.16
[INFO] tweaked toml for crates.io crate tracing 0.1.16 written to /workspace/builds/worker-12/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] ff521356db9834ddeb747ff77c941f4c4d137c79ae7d05f021c9f9e349687142
[INFO] running `Command { std: "docker" "start" "-a" "ff521356db9834ddeb747ff77c941f4c4d137c79ae7d05f021c9f9e349687142", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ff521356db9834ddeb747ff77c941f4c4d137c79ae7d05f021c9f9e349687142", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ff521356db9834ddeb747ff77c941f4c4d137c79ae7d05f021c9f9e349687142", kill_on_drop: false }`
[INFO] [stdout] ff521356db9834ddeb747ff77c941f4c4d137c79ae7d05f021c9f9e349687142
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b71f3de65a63b07d6a711caf32d3b128dbb9bce8d525df04d1a28ad9d1494ff7
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "b71f3de65a63b07d6a711caf32d3b128dbb9bce8d525df04d1a28ad9d1494ff7", kill_on_drop: false }`
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling syn v1.0.35
[INFO] [stderr]    Compiling tracing-core v0.1.11
[INFO] [stderr]    Compiling tracing-attributes v0.1.9
[INFO] [stderr]    Compiling tracing v0.1.16 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 25.17s
[INFO] running `Command { std: "docker" "inspect" "b71f3de65a63b07d6a711caf32d3b128dbb9bce8d525df04d1a28ad9d1494ff7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b71f3de65a63b07d6a711caf32d3b128dbb9bce8d525df04d1a28ad9d1494ff7", kill_on_drop: false }`
[INFO] [stdout] b71f3de65a63b07d6a711caf32d3b128dbb9bce8d525df04d1a28ad9d1494ff7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 694993a05e70e8dc372a284e3cfd6cf1452ec3ecd86b705ec392392ec0e1cc89
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "694993a05e70e8dc372a284e3cfd6cf1452ec3ecd86b705ec392392ec0e1cc89", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v1.0.0
[INFO] [stderr]    Compiling maybe-uninit v2.0.0
[INFO] [stderr]    Compiling libc v0.2.73
[INFO] [stderr]    Compiling semver-parser v0.7.0
[INFO] [stderr]    Compiling ryu v1.0.5
[INFO] [stderr]    Compiling byteorder v1.3.4
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling serde_json v1.0.56
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling itoa v0.4.6
[INFO] [stderr]    Compiling serde_derive v1.0.114
[INFO] [stderr]    Compiling unicode-width v0.1.8
[INFO] [stderr]    Compiling log v0.4.11
[INFO] [stderr]    Compiling futures v0.1.29
[INFO] [stderr]    Compiling serde_cbor v0.11.1
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling clap v2.33.1
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling memoffset v0.5.5
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling rayon v1.3.1
[INFO] [stderr]    Compiling rustc_version v0.2.3
[INFO] [stderr]    Compiling regex-automata v0.1.9
[INFO] [stderr]    Compiling cast v0.2.3
[INFO] [stderr]    Compiling bstr v0.2.13
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling criterion-plot v0.4.3
[INFO] [stderr]    Compiling crossbeam-deque v0.7.3
[INFO] [stderr]    Compiling tinytemplate v1.1.0
[INFO] [stderr]    Compiling rayon-core v1.7.1
[INFO] [stderr]    Compiling csv v1.1.3
[INFO] [stderr]    Compiling plotters v0.2.15
[INFO] [stderr]    Compiling criterion v0.3.3
[INFO] [stderr]    Compiling tracing v0.1.16 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1m 39s
[INFO] running `Command { std: "docker" "inspect" "694993a05e70e8dc372a284e3cfd6cf1452ec3ecd86b705ec392392ec0e1cc89", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "694993a05e70e8dc372a284e3cfd6cf1452ec3ecd86b705ec392392ec0e1cc89", kill_on_drop: false }`
[INFO] [stdout] 694993a05e70e8dc372a284e3cfd6cf1452ec3ecd86b705ec392392ec0e1cc89
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 54ab5d01a29acc79b501211953158498fd1c10de9e8ea5ba5fd1d32b53dde979
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "54ab5d01a29acc79b501211953158498fd1c10de9e8ea5ba5fd1d32b53dde979", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.48s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/tracing-c730352c5c0c93f5
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/event-fce6ea41aa44caf6
[INFO] [stdout] test level_filters::tests::filter_level_conversion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test moved_field ... ok
[INFO] [stdout] test one_with_everything ... ok
[INFO] [stdout] test debug_shorthand ... ok
[INFO] [stdout] test both_shorthands ... ok
[INFO] [stdout] test event_with_message ... ok
[INFO] [stdout] test dotted_field_name ... ok
[INFO] [stdout] test string_message_without_delims ... ok
[INFO] [stdout] test nonzeroi32_event_without_message ... ok
[INFO] [stdout] test borrowed_field ... ok
[INFO] [stdout] test explicit_child ... ok
[INFO] [stdout] test display_shorthand ... ok
[INFO] [stdout] test event_without_message ... ok
[INFO] [stdout] test wrapping_event_without_message ... ok
[INFO] [stdout] test move_field_out_of_struct ... ok
[INFO] [stdout] test explicit_child_at_levels ... ok
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/filter_caching_is_lexically_scoped-750a7525f1ce147f
[INFO] [stdout] test message_without_delims ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test filter_caching_is_lexically_scoped ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/filters_are_not_reevaluated_for_the_same_span-57ebb6e1ee1ac0f0
[INFO] [stdout] test filters_are_not_reevaluated_for_the_same_span ... ok
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/filters_are_reevaluated_for_different_call_sites-1bd29f4003709516
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test filters_are_reevaluated_for_different_call_sites ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/macro_imports-1470939eb63aaa86
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/macros-9b51cf28d7e8e6e6
[INFO] [stdout] test prefixed_event_macros ... ok
[INFO] [stdout] test prefixed_span_macros ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test debug_root ... ok
[INFO] [stdout] test debug_span ... ok
[INFO] [stdout] test debug ... ok
[INFO] [stdout] test debug_span_root ... ok
[INFO] [stdout] test debug_span_with_parent ... ok
[INFO] [stdout] test callsite_macro_api ... ok
[INFO] [stdout] test error ... ok
[INFO] [stdout] test error_span_with_parent ... ok
[INFO] [stdout] test error_span_root ... ok
[INFO] [stdout] test error_span ... ok
[INFO] [stdout] test error_root ... ok
[INFO] [stdout] test event ... ok
[INFO] [stdout] test debug_with_parent ... ok
[INFO] [stdout] test info_span_root ... ok
[INFO] [stdout] test field_shorthand_only ... ok
[INFO] [stdout] test info ... ok
[INFO] [stdout] test info_span ... ok
[INFO] [stdout] test info_with_parent ... ok
[INFO] [stdout] test error_with_parent ... ok
[INFO] [stdout] test event_root ... ok
[INFO] [stdout] test locals_no_message ... ok
[INFO] [stdout] test info_root ... ok
[INFO] [stdout] test info_span_with_parent ... ok
[INFO] [stdout] test trace_root ... ok
[INFO] [stdout] test trace_span ... ok
[INFO] [stdout] test span_with_parent ... ok
[INFO] [stdout] test span ... ok
[INFO] [stdout] test event_with_parent ... ok
[INFO] [stdout] test locals_with_message ... ok
[INFO] [stdout] test span_root ... ok
[INFO] [stdout] test trace ... ok
[INFO] [stdout] test warn ... ok
[INFO] [stdout] test trace_span_root ... ok
[INFO] [stdout] test warn_with_parent ... ok
[INFO] [stdout] test trace_with_parent ... ok
[INFO] [stdout] test warn_span ... ok
[INFO] [stdout] test warn_span_root ... ok
[INFO] [stdout] test trace_span_with_parent ... ok
[INFO] [stdout] test warn_span_with_parent ... ok
[INFO] [stdout] test warn_root ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/span-dda046867b93dc6e
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test contextual_root ... ok
[INFO] [stdout] test display_shorthand ... ok
[INFO] [stdout] test debug_shorthand ... ok
[INFO] [stdout] test contextual_child ... ok
[INFO] [stdout] test clone_and_drop_span_always_go_to_the_subscriber_that_tagged_the_span ... ok
[INFO] [stdout] test borrowed_field ... ok
[INFO] [stdout] test cloning_a_span_calls_clone_span ... ok
[INFO] [stdout] test dotted_field_name ... ok
[INFO] [stdout] test drop_span_when_exiting_dispatchers_context ... ok
[INFO] [stdout] test event_outside_of_span ... ok
[INFO] [stdout] test both_shorthands ... ok
[INFO] [stdout] test explicit_root_span_is_root ... ok
[INFO] [stdout] test explicit_child ... ok
[INFO] [stdout] test handles_to_different_spans_are_not_equal ... ok
[INFO] [stdout] test enter ... ok
[INFO] [stdout] test explicit_root_span_is_root_regardless_of_ctx ... ok
[INFO] [stdout] test explicit_child_regardless_of_ctx ... ok
[INFO] [stdout] test handles_to_the_same_span_are_equal ... ok
[INFO] [stdout] test dropping_a_span_calls_drop_span ... ok
[INFO] [stdout] test move_field_out_of_struct ... ok
[INFO] [stdout] test explicit_child_at_levels ... ok
[INFO] [stdout] test handles_to_different_spans_with_the_same_metadata_are_not_equal ... ok
[INFO] [stdout] test span_closes_after_event ... ok
[INFO] [stdout] test record_new_values_for_fields ... ok
[INFO] [stdout] test new_span_after_event ... ok
[INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them ... ok
[INFO] [stdout] test new_span_with_target_and_log_level ... ok
[INFO] [stdout] test moved_field ... ok
[INFO] [stdout] test span_closes_when_exited ... ok
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/subscriber-80cd09392269d2e2
[INFO] [stdout] test record_new_value_for_field ... ok
[INFO] [stdout] enter: foo; id=Id(1);
[INFO] [stdout] exit: foo; id=Id(1);
[INFO] [stdout] drop_span: foo; id=Id(1); refs=1;
[INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them_even_across_threads ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Doc-tests tracing
[INFO] [stdout] test event_macros_dont_infinite_loop ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test src/lib.rs - (line 162) ... ignored
[INFO] [stdout] test src/lib.rs - (line 384) ... ok
[INFO] [stdout] test src/dispatcher.rs - dispatcher (line 25) ... ok
[INFO] [stdout] test src/lib.rs - (line 284) ... ok
[INFO] [stdout] test src/lib.rs - (line 474) ... ignored
[INFO] [stdout] test src/dispatcher.rs - dispatcher (line 48) ... ok
[INFO] [stdout] test src/lib.rs - (line 245) ... ok
[INFO] [stdout] test src/lib.rs - (line 197) ... ok
[INFO] [stdout] test src/lib.rs - (line 368) ... ok
[INFO] [stdout] test src/lib.rs - (line 138) ... ok
[INFO] [stdout] test src/dispatcher.rs - dispatcher (line 83) ... ok
[INFO] [stdout] test src/lib.rs - (line 347) ... ok
[INFO] [stdout] test src/lib.rs - (line 220) ... ok
[INFO] [stdout] test src/lib.rs - (line 326) ... ok
[INFO] [stdout] test src/lib.rs - (line 228) ... ok
[INFO] [stdout] test src/lib.rs - (line 272) ... ok
[INFO] [stdout] test src/lib.rs - (line 259) ... ok
[INFO] [stdout] test src/lib.rs - (line 304) ... ok
[INFO] [stdout] test src/lib.rs - (line 594) ... ok
[INFO] [stdout] test src/lib.rs - (line 407) ... ok
[INFO] [stdout] test src/lib.rs - (line 39) ... ok
[INFO] [stdout] test src/lib.rs - (line 72) ... ok
[INFO] [stdout] test src/lib.rs - (line 552) ... ok
[INFO] [stdout] test src/macros.rs - debug_span (line 174) ... ok
[INFO] [stdout] test src/macros.rs - error (line 1573) ... ok
[INFO] [stdout] test src/macros.rs - debug (line 955) ... ok
[INFO] [stdout] test src/macros.rs - debug_span (line 183) ... ok
[INFO] [stdout] test src/macros.rs - error_span (line 416) ... ok
[INFO] [stdout] test src/macros.rs - info_span (line 255) ... ok
[INFO] [stdout] test src/macros.rs - error_span (line 425) ... ok
[INFO] [stdout] test src/macros.rs - info_span (line 264) ... ok
[INFO] [stdout] test src/macros.rs - span (line 11) ... ok
[INFO] [stdout] test src/macros.rs - info (line 1156) ... ok
[INFO] [stdout] test src/macros.rs - event (line 495) ... ok
[INFO] [stdout] test src/macros.rs - trace_span (line 93) ... ok
[INFO] [stdout] test src/macros.rs - trace_span (line 102) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 592) ... ignored
[INFO] [stdout] test src/span.rs - span::Span::enter (line 617) ... ignored
[INFO] [stdout] test src/span.rs - span::Span::enter (line 636) ... ignored
[INFO] [stdout] test src/macros.rs - warn_span (line 345) ... ok
[INFO] [stdout] test src/macros.rs - warn (line 1368) ... ok
[INFO] [stdout] test src/macros.rs - trace (line 755) ... ok
[INFO] [stdout] test src/span.rs - span (line 198) ... ok
[INFO] [stdout] test src/macros.rs - warn_span (line 336) ... ok
[INFO] [stdout] test src/span.rs - span (line 154) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 537) ... ok
[INFO] [stdout] test src/span.rs - span (line 132) ... ok
[INFO] [stdout] test src/span.rs - span (line 288) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 674) ... ok
[INFO] [stdout] test src/span.rs - span (line 240) ... ok
[INFO] [stdout] test src/span.rs - span (line 66) ... ok
[INFO] [stdout] test src/span.rs - span (line 31) ... ok
[INFO] [stdout] test src/span.rs - span (line 275) ... ok
[INFO] [stdout] test src/span.rs - span (line 94) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 567) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 658) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 950) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 696) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 957) ... ok
[INFO] [stdout] test src/span.rs - span::Span::in_scope (line 752) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 942) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 965) ... ok
[INFO] [stdout] test src/span.rs - span::Span::record (line 791) ... ok
[INFO] [stdout] test src/span.rs - span::Span::record (line 837) ... ok
[INFO] [stdout] test src/span.rs - span::Span::in_scope (line 737) ... ok
[INFO] [stdout] test src/span.rs - span::Span::record (line 806) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 61 passed; 0 failed; 5 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "54ab5d01a29acc79b501211953158498fd1c10de9e8ea5ba5fd1d32b53dde979", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "54ab5d01a29acc79b501211953158498fd1c10de9e8ea5ba5fd1d32b53dde979", kill_on_drop: false }`
[INFO] [stdout] 54ab5d01a29acc79b501211953158498fd1c10de9e8ea5ba5fd1d32b53dde979