[INFO] fetching crate tracing 0.1.31...
[INFO] testing tracing-0.1.31 against 1.59.0 for beta-1.60-1
[INFO] extracting crate tracing 0.1.31 into /workspace/builds/worker-28/source
[INFO] validating manifest of crates.io crate tracing 0.1.31 on toolchain 1.59.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate tracing 0.1.31
[INFO] finished tweaking crates.io crate tracing 0.1.31
[INFO] tweaked toml for crates.io crate tracing 0.1.31 written to /workspace/builds/worker-28/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.0" "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" "+1.59.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 83bd29fc149346ab81930c4b4915f2410667893d2d3ada566e66cfa1b0670e14
[INFO] running `Command { std: "docker" "start" "-a" "83bd29fc149346ab81930c4b4915f2410667893d2d3ada566e66cfa1b0670e14", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "83bd29fc149346ab81930c4b4915f2410667893d2d3ada566e66cfa1b0670e14", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "83bd29fc149346ab81930c4b4915f2410667893d2d3ada566e66cfa1b0670e14", kill_on_drop: false }`
[INFO] [stdout] 83bd29fc149346ab81930c4b4915f2410667893d2d3ada566e66cfa1b0670e14
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0f48c0edbae5624820c0251d9fc2ed7c4c7ef4a1a1ba04e709a7b2f557f959ad
[INFO] running `Command { std: "docker" "start" "-a" "0f48c0edbae5624820c0251d9fc2ed7c4c7ef4a1a1ba04e709a7b2f557f959ad", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling syn v1.0.86
[INFO] [stderr]    Compiling pin-project-lite v0.2.8
[INFO] [stderr]    Compiling tracing-core v0.1.22
[INFO] [stderr]    Compiling tracing-attributes v0.1.19
[INFO] [stderr]    Compiling tracing v0.1.31 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.38s
[INFO] running `Command { std: "docker" "inspect" "0f48c0edbae5624820c0251d9fc2ed7c4c7ef4a1a1ba04e709a7b2f557f959ad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0f48c0edbae5624820c0251d9fc2ed7c4c7ef4a1a1ba04e709a7b2f557f959ad", kill_on_drop: false }`
[INFO] [stdout] 0f48c0edbae5624820c0251d9fc2ed7c4c7ef4a1a1ba04e709a7b2f557f959ad
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] deac81a24a3bd5f01350305a5099cd69b7ad723de1c57f13c16c4bc9370a2e68
[INFO] running `Command { std: "docker" "start" "-a" "deac81a24a3bd5f01350305a5099cd69b7ad723de1c57f13c16c4bc9370a2e68", kill_on_drop: false }`
[INFO] [stderr]    Compiling semver v1.0.6
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.7
[INFO] [stderr]    Compiling rayon-core v1.9.1
[INFO] [stderr]    Compiling plotters-backend v0.3.2
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling half v1.8.2
[INFO] [stderr]    Compiling oorandom v11.1.3
[INFO] [stderr]    Compiling rayon v1.5.1
[INFO] [stderr]    Compiling itertools v0.10.3
[INFO] [stderr]    Compiling tokio v0.2.25
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling crossbeam-channel v0.5.2
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling plotters-svg v0.3.1
[INFO] [stderr]    Compiling serde_cbor v0.11.2
[INFO] [stderr]    Compiling plotters v0.3.1
[INFO] [stderr]    Compiling crossbeam-deque v0.8.1
[INFO] [stderr]    Compiling csv-core v0.1.10
[INFO] [stderr]    Compiling bstr v0.2.17
[INFO] [stderr]    Compiling rustc_version v0.4.0
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling cast v0.2.7
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling criterion-plot v0.4.4
[INFO] [stderr]    Compiling criterion v0.3.5
[INFO] [stderr]    Compiling tracing v0.1.31 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 18.59s
[INFO] running `Command { std: "docker" "inspect" "deac81a24a3bd5f01350305a5099cd69b7ad723de1c57f13c16c4bc9370a2e68", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "deac81a24a3bd5f01350305a5099cd69b7ad723de1c57f13c16c4bc9370a2e68", kill_on_drop: false }`
[INFO] [stdout] deac81a24a3bd5f01350305a5099cd69b7ad723de1c57f13c16c4bc9370a2e68
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-28/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 481ecaec9e15d89eebcce48c7db72c44088b2241c20eaccf7f44011fa956ba35
[INFO] running `Command { std: "docker" "start" "-a" "481ecaec9e15d89eebcce48c7db72c44088b2241c20eaccf7f44011fa956ba35", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/tracing-49c7de49bf8a5846)
[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] [stderr]      Running tests/enabled.rs (/opt/rustwide/target/debug/deps/enabled-1d6b0fb3f9989804)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test level_and_target ... 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] [stderr]      Running tests/event.rs (/opt/rustwide/target/debug/deps/event-e2768459a05a112e)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test both_shorthands ... ok
[INFO] [stdout] test borrowed_field ... ok
[INFO] [stdout] test debug_shorthand ... 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 dotted_field_name ... ok
[INFO] [stdout] test event_with_message ... ok
[INFO] [stdout] test nonzeroi32_event_without_message ... ok
[INFO] [stdout] test moved_field ... ok
[INFO] [stdout] test explicit_child ... ok
[INFO] [stdout] test option_values ... ok
[INFO] [stdout] test option_ref_mut_values ... ok
[INFO] [stdout] test message_without_delims ... ok
[INFO] [stdout] test one_with_everything ... ok
[INFO] [stdout] test string_message_without_delims ... ok
[INFO] [stdout] test option_ref_values ... ok
[INFO] [stdout] test move_field_out_of_struct ... ok
[INFO] [stdout] test explicit_child_at_levels ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/filter_caching_is_lexically_scoped.rs (/opt/rustwide/target/debug/deps/filter_caching_is_lexically_scoped-6c3261974cb7698b)
[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.00s
[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-e9895273b4cd45d0)
[INFO] [stdout] 
[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] [stderr]      Running tests/filters_are_reevaluated_for_different_call_sites.rs (/opt/rustwide/target/debug/deps/filters_are_reevaluated_for_different_call_sites-011e39a11aad2aaf)
[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; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/filters_dont_leak.rs (/opt/rustwide/target/debug/deps/filters_dont_leak-0805cb0a7d00a30b)
[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.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macro_imports.rs (/opt/rustwide/target/debug/deps/macro_imports-0e8089c0351e3af3)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[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; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macros.rs (/opt/rustwide/target/debug/deps/macros-f8e7455bac3aed24)
[INFO] [stdout] 
[INFO] [stdout] running 44 tests
[INFO] [stdout] test borrow_val_events ... ok
[INFO] [stdout] test borrow_val_spans ... ok
[INFO] [stdout] test callsite_macro_api ... ok
[INFO] [stdout] test debug ... ok
[INFO] [stdout] test debug_span ... ok
[INFO] [stdout] test debug_root ... ok
[INFO] [stdout] test debug_span_root ... ok
[INFO] [stdout] test debug_span_with_parent ... ok
[INFO] [stdout] test debug_with_parent ... ok
[INFO] [stdout] test enabled ... ok
[INFO] [stdout] test error ... ok
[INFO] [stdout] test error_root ... ok
[INFO] [stdout] test error_span ... ok
[INFO] [stdout] test error_span_root ... ok
[INFO] [stdout] test error_span_with_parent ... ok
[INFO] [stdout] test error_with_parent ... ok
[INFO] [stdout] test event ... ok
[INFO] [stdout] test event_root ... ok
[INFO] [stdout] test event_with_parent ... ok
[INFO] [stdout] test field_shorthand_only ... ok
[INFO] [stdout] test info ... ok
[INFO] [stdout] test info_root ... ok
[INFO] [stdout] test info_span ... ok
[INFO] [stdout] test info_span_root ... ok
[INFO] [stdout] test info_span_with_parent ... ok
[INFO] [stdout] test locals_no_message ... ok
[INFO] [stdout] test info_with_parent ... ok
[INFO] [stdout] test locals_with_message ... ok
[INFO] [stdout] test span ... ok
[INFO] [stdout] test span_root ... ok
[INFO] [stdout] test span_with_non_rust_symbol ... ok
[INFO] [stdout] test span_with_parent ... ok
[INFO] [stdout] test trace ... ok
[INFO] [stdout] test trace_span ... ok
[INFO] [stdout] test trace_root ... ok
[INFO] [stdout] test trace_span_root ... ok
[INFO] [stdout] test trace_span_with_parent ... ok
[INFO] [stdout] test trace_with_parent ... ok
[INFO] [stdout] test warn_root ... ok
[INFO] [stdout] test warn ... ok
[INFO] [stdout] test warn_span ... ok
[INFO] [stdout] test warn_span_root ... ok
[INFO] [stdout] test warn_span_with_parent ... ok
[INFO] [stdout] test warn_with_parent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 44 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macros_incompatible_concat.rs (/opt/rustwide/target/debug/deps/macros_incompatible_concat-4d74c8dc6d3adb31)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test enabled ... ok
[INFO] [stdout] test event ... ok
[INFO] [stdout] test span ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macros_redefined_core.rs (/opt/rustwide/target/debug/deps/macros_redefined_core-ed1101a5cbbdb1c9)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test enabled ... ok
[INFO] [stdout] test event ... ok
[INFO] [stdout] test span ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/max_level_hint.rs (/opt/rustwide/target/debug/deps/max_level_hint-d66a95fa463bf020)
[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.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/multiple_max_level_hints.rs (/opt/rustwide/target/debug/deps/multiple_max_level_hints-a49f929f0bec8098)
[INFO] [stdout] 
[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] [stderr]      Running tests/span.rs (/opt/rustwide/target/debug/deps/span-500dbd0ac39870ee)
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test borrowed_field ... ok
[INFO] [stdout] test cloning_a_span_calls_clone_span ... ok
[INFO] [stdout] test clone_and_drop_span_always_go_to_the_subscriber_that_tagged_the_span ... ok
[INFO] [stdout] test debug_shorthand ... ok
[INFO] [stdout] test explicit_root_span_is_root ... ok
[INFO] [stdout] test display_shorthand ... ok
[INFO] [stdout] test contextual_child ... ok
[INFO] [stdout] test float_values ... ok
[INFO] [stdout] test handles_to_different_spans_with_the_same_metadata_are_not_equal ... ok
[INFO] [stdout] test dropping_a_span_calls_drop_span ... ok
[INFO] [stdout] test contextual_root ... ok
[INFO] [stdout] test record_new_value_for_field ... ok
[INFO] [stdout] test move_field_out_of_struct ... ok
[INFO] [stdout] test span_closes_when_exited ... ok
[INFO] [stdout] test entered ... ok
[INFO] [stdout] test entered_api ... ok
[INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them_even_across_threads ... ok
[INFO] [stdout] test dotted_field_name ... ok
[INFO] [stdout] test explicit_child ... ok
[INFO] [stdout] test drop_span_when_exiting_dispatchers_context ... ok
[INFO] [stdout] test moved_field ... ok
[INFO] [stdout] test both_shorthands ... ok
[INFO] [stdout] test record_new_values_for_fields ... ok
[INFO] [stdout] test span_closes_after_event ... ok
[INFO] [stdout] test event_outside_of_span ... ok
[INFO] [stdout] test explicit_root_span_is_root_regardless_of_ctx ... ok
[INFO] [stdout] test handles_to_different_spans_are_not_equal ... ok
[INFO] [stdout] test handles_to_the_same_span_are_equal ... ok
[INFO] [stdout] test enter ... 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 explicit_child_at_levels ... ok
[INFO] [stdout] test explicit_child_regardless_of_ctx ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/subscriber.rs (/opt/rustwide/target/debug/deps/subscriber-f26ebbd7211f28e2)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test boxed_subscriber ... ok
[INFO] [stdout] test arced_subscriber ... ok
[INFO] [stdout] test event_macros_dont_infinite_loop ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests tracing
[INFO] [stdout] 
[INFO] [stdout] running 88 tests
[INFO] [stdout] test src/field.rs - field (line 55) ... ignored
[INFO] [stdout] test src/lib.rs - (line 160) ... ignored
[INFO] [stdout] test src/lib.rs - (line 481) ... ignored
[INFO] [stdout] test src/lib.rs - (line 393) - compile fail ... ok
[INFO] [stdout] test src/instrument.rs - instrument::WithSubscriber::with_current_subscriber (line 190) ... ok
[INFO] [stderr] error: test failed, to rerun pass '--doc'
[INFO] [stderr] 
[INFO] [stderr] Caused by:
[INFO] [stderr]   process didn't exit successfully: `rustdoc --edition=2018 --crate-type lib --crate-name tracing --test /opt/rustwide/workdir/src/lib.rs -L dependency=/opt/rustwide/target/debug/deps -L dependency=/opt/rustwide/target/debug/deps --extern cfg_if=/opt/rustwide/target/debug/deps/libcfg_if-0a04e74fc4f21d18.rlib --extern criterion=/opt/rustwide/target/debug/deps/libcriterion-fdb7c15801d36cf5.rlib --extern futures=/opt/rustwide/target/debug/deps/libfutures-71764451b30370be.rlib --extern log=/opt/rustwide/target/debug/deps/liblog-b6c99e3b18835146.rlib --extern pin_project_lite=/opt/rustwide/target/debug/deps/libpin_project_lite-420daa249e18d18f.rlib --extern tokio=/opt/rustwide/target/debug/deps/libtokio-b8abfc921af30b14.rlib --extern tracing=/opt/rustwide/target/debug/deps/libtracing-552ddcdcf18eb5be.rlib --extern tracing_attributes=/opt/rustwide/target/debug/deps/libtracing_attributes-8992609944964119.so --extern tracing_core=/opt/rustwide/target/debug/deps/libtracing_core-eb7c12311019b1a5.rlib -C embed-bitcode=no --cfg 'feature="attributes"' --cfg 'feature="default"' --cfg 'feature="std"' --cfg 'feature="tracing-attributes"' --error-format human` (signal: 9, SIGKILL: kill)
[INFO] running `Command { std: "docker" "inspect" "481ecaec9e15d89eebcce48c7db72c44088b2241c20eaccf7f44011fa956ba35", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "481ecaec9e15d89eebcce48c7db72c44088b2241c20eaccf7f44011fa956ba35", kill_on_drop: false }`
[INFO] [stdout] 481ecaec9e15d89eebcce48c7db72c44088b2241c20eaccf7f44011fa956ba35