[INFO] fetching crate tracing 0.1.26...
[INFO] testing tracing-0.1.26 against master#b70888601af92f6cdc0364abab3446e418b91d36 for pr-86479
[INFO] extracting crate tracing 0.1.26 into /workspace/builds/worker-8/source
[INFO] validating manifest of crates.io crate tracing 0.1.26 on toolchain b70888601af92f6cdc0364abab3446e418b91d36
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate tracing 0.1.26
[INFO] finished tweaking crates.io crate tracing 0.1.26
[INFO] tweaked toml for crates.io crate tracing 0.1.26 written to /workspace/builds/worker-8/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "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" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4a02fa9d2af3bb76902143d83287bea5b3dc2ff3e93718ab9513761e6a00fdfd
[INFO] running `Command { std: "docker" "start" "-a" "4a02fa9d2af3bb76902143d83287bea5b3dc2ff3e93718ab9513761e6a00fdfd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4a02fa9d2af3bb76902143d83287bea5b3dc2ff3e93718ab9513761e6a00fdfd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4a02fa9d2af3bb76902143d83287bea5b3dc2ff3e93718ab9513761e6a00fdfd", kill_on_drop: false }`
[INFO] [stdout] 4a02fa9d2af3bb76902143d83287bea5b3dc2ff3e93718ab9513761e6a00fdfd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "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" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2c39b8b46c01fd34ce503d000239db0ebea30b4d410a64fc53319b63f0a2e777
[INFO] running `Command { std: "docker" "start" "-a" "2c39b8b46c01fd34ce503d000239db0ebea30b4d410a64fc53319b63f0a2e777", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.28
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling pin-project-lite v0.2.7
[INFO] [stderr]    Compiling tracing-core v0.1.18
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling syn v1.0.74
[INFO] [stderr]    Compiling tracing-attributes v0.1.15
[INFO] [stderr]    Compiling tracing v0.1.26 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.42s
[INFO] running `Command { std: "docker" "inspect" "2c39b8b46c01fd34ce503d000239db0ebea30b4d410a64fc53319b63f0a2e777", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2c39b8b46c01fd34ce503d000239db0ebea30b4d410a64fc53319b63f0a2e777", kill_on_drop: false }`
[INFO] [stdout] 2c39b8b46c01fd34ce503d000239db0ebea30b4d410a64fc53319b63f0a2e777
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "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" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 25c3d2dcfa8dd02f89c618c63d81ac3457834a14cd4def852837bbba6bb658c2
[INFO] running `Command { std: "docker" "start" "-a" "25c3d2dcfa8dd02f89c618c63d81ac3457834a14cd4def852837bbba6bb658c2", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.98
[INFO] [stderr]    Compiling bytes v0.5.6
[INFO] [stderr]    Compiling pin-project-lite v0.1.12
[INFO] [stderr]    Compiling crossbeam-utils v0.8.5
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling bstr v0.2.16
[INFO] [stderr]    Compiling csv-core v0.1.10
[INFO] [stderr]    Compiling serde_derive v1.0.127
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.5
[INFO] [stderr]    Compiling crossbeam-channel v0.5.1
[INFO] [stderr]    Compiling tokio v0.2.25
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling crossbeam-deque v0.8.1
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling rayon-core v1.9.1
[INFO] [stderr]    Compiling rayon v1.5.1
[INFO] [stderr]    Compiling criterion v0.3.5
[INFO] [stderr]    Compiling tracing v0.1.26 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 36.21s
[INFO] running `Command { std: "docker" "inspect" "25c3d2dcfa8dd02f89c618c63d81ac3457834a14cd4def852837bbba6bb658c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "25c3d2dcfa8dd02f89c618c63d81ac3457834a14cd4def852837bbba6bb658c2", kill_on_drop: false }`
[INFO] [stdout] 25c3d2dcfa8dd02f89c618c63d81ac3457834a14cd4def852837bbba6bb658c2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "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" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2e729762a6d82670b47d3b9e1426c2102e7850234a51bbdeffd2f3cdf1066f88
[INFO] running `Command { std: "docker" "start" "-a" "2e729762a6d82670b47d3b9e1426c2102e7850234a51bbdeffd2f3cdf1066f88", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/tracing-24183e90d25d2611)
[INFO] [stderr]      Running tests/event.rs (/opt/rustwide/target/debug/deps/event-27174f869b78870a)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test borrowed_field ... ok
[INFO] [stdout] test explicit_child ... ok
[INFO] [stdout] test wrapping_event_without_message ... ok
[INFO] [stdout] test move_field_out_of_struct ... ok
[INFO] [stdout] test string_message_without_delims ... ok
[INFO] [stderr]      Running tests/filter_caching_is_lexically_scoped.rs (/opt/rustwide/target/debug/deps/filter_caching_is_lexically_scoped-06e199cd0128ae72)
[INFO] [stdout] test explicit_child_at_levels ... ok
[INFO] [stdout] test message_without_delims ... ok
[INFO] [stdout] test moved_field ... ok
[INFO] [stdout] test nonzeroi32_event_without_message ... ok
[INFO] [stdout] test one_with_everything ... ok
[INFO] [stdout] test both_shorthands ... ok
[INFO] [stdout] test display_shorthand ... ok
[INFO] [stdout] test event_without_message ... ok
[INFO] [stdout] test event_with_message ... ok
[INFO] [stdout] test dotted_field_name ... ok
[INFO] [stdout] test debug_shorthand ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[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; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/filters_are_not_reevaluated_for_the_same_span.rs (/opt/rustwide/target/debug/deps/filters_are_not_reevaluated_for_the_same_span-337e193e0dc7e51b)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/filters_are_reevaluated_for_different_call_sites.rs (/opt/rustwide/target/debug/deps/filters_are_reevaluated_for_different_call_sites-f79395a8ef27587d)
[INFO] [stdout] running 1 test
[INFO] [stdout] test filters_are_not_reevaluated_for_the_same_span ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/filters_dont_leak.rs (/opt/rustwide/target/debug/deps/filters_dont_leak-7e3262be69cb70cc)
[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; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test events_dont_leak ... ok
[INFO] [stdout] test spans_dont_leak ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macro_imports.rs (/opt/rustwide/target/debug/deps/macro_imports-aab4036add92a9b9)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test prefixed_span_macros ... ok
[INFO] [stderr]      Running tests/macros.rs (/opt/rustwide/target/debug/deps/macros-431b6a9d74f0386c)
[INFO] [stdout] test prefixed_event_macros ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 43 tests
[INFO] [stdout] test borrow_val_spans ... ok
[INFO] [stdout] test callsite_macro_api ... ok
[INFO] [stdout] test debug_span ... ok
[INFO] [stdout] test error ... ok
[INFO] [stdout] test debug_span_with_parent ... ok
[INFO] [stdout] test debug_with_parent ... ok
[INFO] [stdout] test error_span ... ok
[INFO] [stdout] test error_root ... ok
[INFO] [stdout] test event ... ok
[INFO] [stdout] test event_with_parent ... ok
[INFO] [stdout] test field_shorthand_only ... ok
[INFO] [stdout] test info ... ok
[INFO] [stdout] test info_span_root ... ok
[INFO] [stdout] test span ... ok
[INFO] [stdout] test span_root ... ok
[INFO] [stdout] test locals_with_message ... ok
[INFO] [stdout] test warn_root ... ok
[INFO] [stdout] test warn_span ... ok
[INFO] [stdout] test error_with_parent ... ok
[INFO] [stdout] test error_span_root ... ok
[INFO] [stdout] test warn_span_with_parent ... ok
[INFO] [stdout] test warn_with_parent ... ok
[INFO] [stdout] test warn_span_root ... ok
[INFO] [stdout] test info_span_with_parent ... ok
[INFO] [stdout] test locals_no_message ... ok
[INFO] [stdout] test info_span ... ok
[INFO] [stdout] test info_with_parent ... ok
[INFO] [stdout] test trace_span_root ... ok
[INFO] [stdout] test trace_span_with_parent ... ok
[INFO] [stdout] test trace ... ok
[INFO] [stdout] test trace_root ... ok
[INFO] [stdout] test trace_with_parent ... ok
[INFO] [stdout] test error_span_with_parent ... ok
[INFO] [stdout] test borrow_val_events ... ok
[INFO] [stdout] test trace_span ... ok
[INFO] [stdout] test warn ... ok
[INFO] [stderr]      Running tests/max_level_hint.rs (/opt/rustwide/target/debug/deps/max_level_hint-5317ec2d888a122c)
[INFO] [stdout] test debug ... ok
[INFO] [stdout] test debug_root ... ok
[INFO] [stdout] test span_with_non_rust_symbol ... ok
[INFO] [stdout] test span_with_parent ... ok
[INFO] [stdout] test event_root ... ok
[INFO] [stdout] test debug_span_root ... ok
[INFO] [stdout] test info_root ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 43 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test max_level_hints ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/multiple_max_level_hints.rs (/opt/rustwide/target/debug/deps/multiple_max_level_hints-08c09ccd11c1ed59)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/span.rs (/opt/rustwide/target/debug/deps/span-c14e269182a8adad)
[INFO] [stdout] running 1 test
[INFO] [stdout] test multiple_max_level_hints ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 33 tests
[INFO] [stdout] test dropping_a_span_calls_drop_span ... ok
[INFO] [stdout] test entered_api ... ok
[INFO] [stdout] test entered ... ok
[INFO] [stdout] test clone_and_drop_span_always_go_to_the_subscriber_that_tagged_the_span ... ok
[INFO] [stdout] test cloning_a_span_calls_clone_span ... ok
[INFO] [stdout] test borrowed_field ... ok
[INFO] [stdout] test contextual_child ... ok
[INFO] [stdout] test span_closes_after_event ... ok
[INFO] [stdout] test debug_shorthand ... ok
[INFO] [stdout] test record_new_values_for_fields ... ok
[INFO] [stdout] test both_shorthands ... ok
[INFO] [stdout] test drop_span_when_exiting_dispatchers_context ... ok
[INFO] [stdout] test enter ... ok
[INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them ... ok
[INFO] [stdout] test handles_to_different_spans_with_the_same_metadata_are_not_equal ... ok
[INFO] [stdout] test event_outside_of_span ... ok
[INFO] [stdout] test explicit_root_span_is_root_regardless_of_ctx ... ok
[INFO] [stdout] test explicit_root_span_is_root ... ok
[INFO] [stdout] test explicit_child ... ok
[INFO] [stdout] test handles_to_the_same_span_are_equal ... ok
[INFO] [stdout] test explicit_child_at_levels ... ok
[INFO] [stdout] test new_span_with_target_and_log_level ... ok
[INFO] [stdout] test record_new_value_for_field ... ok
[INFO] [stdout] test handles_to_different_spans_are_not_equal ... ok
[INFO] [stdout] test span_closes_when_exited ... ok
[INFO] [stdout] test contextual_root ... ok
[INFO] [stdout] test move_field_out_of_struct ... ok
[INFO] [stdout] test display_shorthand ... ok
[INFO] [stdout] test dotted_field_name ... ok
[INFO] [stdout] test moved_field ... ok
[INFO] [stdout] test new_span_after_event ... ok
[INFO] [stdout] test explicit_child_regardless_of_ctx ... ok
[INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them_even_across_threads ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/subscriber.rs (/opt/rustwide/target/debug/deps/subscriber-b75703f10855442a)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test event_macros_dont_infinite_loop ... ok
[INFO] [stdout] test arced_subscriber ... ok
[INFO] [stdout] test boxed_subscriber ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests tracing
[INFO] [stdout] 
[INFO] [stdout] running 77 tests
[INFO] [stdout] test src/lib.rs - (line 165) ... ignored
[INFO] [stdout] test src/lib.rs - (line 502) ... ignored
[INFO] [stdout] test src/lib.rs - (line 187) ... ok
[INFO] [stdout] test src/lib.rs - (line 412) - compile fail ... ok
[INFO] [stdout] test src/lib.rs - (line 75) ... ok
[INFO] [stdout] test src/macros.rs - error_span (line 471) ... ok
[INFO] [stdout] test src/lib.rs - (line 319) ... ok
[INFO] [stdout] test src/lib.rs - (line 435) ... ok
[INFO] [stdout] test src/lib.rs - (line 354) ... ok
[INFO] [stdout] test src/macros.rs - debug_span (line 229) ... ok
[INFO] [stdout] test src/lib.rs - (line 298) ... ok
[INFO] [stdout] test src/dispatcher.rs - dispatcher (line 25) ... ok
[INFO] [stdout] test src/lib.rs - (line 211) ... ok
[INFO] [stdout] test src/macros.rs - debug (line 1005) ... ok
[INFO] [stdout] test src/lib.rs - (line 621) ... ok
[INFO] [stdout] test src/lib.rs - (line 42) ... ok
[INFO] [stdout] test src/lib.rs - (line 286) ... ok
[INFO] [stdout] test src/macros.rs - event (line 550) ... ok
[INFO] [stdout] test src/lib.rs - (line 579) ... ok
[INFO] [stdout] test src/lib.rs - (line 332) ... ok
[INFO] [stdout] test src/lib.rs - (line 141) ... ok
[INFO] [stdout] test src/lib.rs - (line 259) ... ok
[INFO] [stdout] test src/macros.rs - debug_span (line 238) ... ok
[INFO] [stdout] test src/lib.rs - (line 234) ... ok
[INFO] [stdout] test src/macros.rs - info_span (line 310) ... ok
[INFO] [stdout] test src/macros.rs - error (line 1623) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 672) ... ignored
[INFO] [stdout] test src/span.rs - span::Span::enter (line 697) ... ignored
[INFO] [stdout] test src/span.rs - span::Span::enter (line 715) ... ignored
[INFO] [stdout] test src/macros.rs - trace_span (line 157) ... ok
[INFO] [stdout] test src/macros.rs - warn_span (line 400) ... ok
[INFO] [stdout] test src/macros.rs - warn (line 1418) ... ok
[INFO] [stdout] test src/lib.rs - (line 396) ... ok
[INFO] [stdout] test src/macros.rs - trace_span (line 148) ... ok
[INFO] [stdout] test src/macros.rs - info_span (line 319) ... ok
[INFO] [stdout] test src/macros.rs - error_span (line 480) ... ok
[INFO] [stdout] test src/span.rs - span (line 292) ... ok
[INFO] [stdout] test src/lib.rs - (line 375) ... ok
[INFO] [stdout] test src/span.rs - span (line 112) ... ok
[INFO] [stdout] test src/lib.rs - (line 273) ... ok
[INFO] [stdout] test src/macros.rs - span (line 11) ... ok
[INFO] [stdout] test src/span.rs - span (line 149) ... ok
[INFO] [stdout] test src/macros.rs - trace (line 805) ... ok
[INFO] [stdout] test src/span.rs - span (line 257) ... ok
[INFO] [stdout] test src/instrument.rs - instrument::Instrument::in_current_span (line 54) ... ok
[INFO] [stdout] test src/macros.rs - warn_span (line 391) ... ok
[INFO] [stdout] test src/span.rs - span::EnteredSpan::_not_send (line 417) - compile fail ... ok
[INFO] [stdout] test src/span.rs - span (line 215) ... ok
[INFO] [stdout] test src/lib.rs - (line 242) ... ok
[INFO] [stdout] test src/macros.rs - info (line 1206) ... ok
[INFO] [stdout] test src/dispatcher.rs - dispatcher (line 83) ... ok
[INFO] [stdout] test src/instrument.rs - instrument::Instrument::instrument (line 23) ... ok
[INFO] [stdout] test src/span.rs - span (line 305) ... ok
[INFO] [stdout] test src/span.rs - span (line 31) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 617) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 647) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 752) ... ok
[INFO] [stdout] test src/span.rs - span::Span::entered (line 890) ... ok
[INFO] [stdout] test src/span.rs - span (line 66) ... ok
[INFO] [stdout] test src/span.rs - span::Span::in_scope (line 969) ... ok
[INFO] [stdout] test src/span.rs - span (line 95) ... ok
[INFO] [stdout] test src/dispatcher.rs - dispatcher (line 48) ... ok
[INFO] [stdout] test src/span.rs - span::Span::entered (line 850) ... ok
[INFO] [stdout] test src/span.rs - span (line 171) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 1174) ... ok
[INFO] [stdout] test src/span.rs - span::Span::entered (line 809) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 774) ... ok
[INFO] [stdout] test src/span.rs - span::Span::enter (line 736) ... ok
[INFO] [stdout] test src/span.rs - span::Span::entered (line 814) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 1167) ... ok
[INFO] [stdout] test src/span.rs - span::Span::in_scope (line 954) ... ok
[INFO] [stdout] test src/span.rs - span::Span::record (line 1023) ... ok
[INFO] [stdout] test src/span.rs - span::Span::entered (line 869) ... ok
[INFO] [stdout] test src/span.rs - span::Span::record (line 1054) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 1159) ... ok
[INFO] [stdout] test src/span.rs - span::Span::follows_from (line 1182) ... ok
[INFO] [stdout] test src/span.rs - span::Span::record (line 1008) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 72 passed; 0 failed; 5 ignored; 0 measured; 0 filtered out; finished in 4.67s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "2e729762a6d82670b47d3b9e1426c2102e7850234a51bbdeffd2f3cdf1066f88", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2e729762a6d82670b47d3b9e1426c2102e7850234a51bbdeffd2f3cdf1066f88", kill_on_drop: false }`
[INFO] [stdout] 2e729762a6d82670b47d3b9e1426c2102e7850234a51bbdeffd2f3cdf1066f88