[INFO] fetching crate tracing-mock 0.1.0-beta.1... [INFO] testing tracing-mock-0.1.0-beta.1 against 1.90.0 for beta-1.91-3 [INFO] extracting crate tracing-mock 0.1.0-beta.1 into /workspace/builds/worker-6-tc1/source [INFO] started tweaking crates.io crate tracing-mock 0.1.0-beta.1 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate tracing-mock 0.1.0-beta.1 [INFO] tweaked toml for crates.io crate tracing-mock 0.1.0-beta.1 written to /workspace/builds/worker-6-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate tracing-mock 0.1.0-beta.1 on toolchain 1.90.0 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 42 packages to latest compatible versions [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 4165ad7d34236c9cce4596957faaf2b57a3aa1dce65f31f01aa41669aef07370 [INFO] running `Command { std: "docker" "start" "-a" "4165ad7d34236c9cce4596957faaf2b57a3aa1dce65f31f01aa41669aef07370", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "4165ad7d34236c9cce4596957faaf2b57a3aa1dce65f31f01aa41669aef07370", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4165ad7d34236c9cce4596957faaf2b57a3aa1dce65f31f01aa41669aef07370", kill_on_drop: false }` [INFO] [stdout] 4165ad7d34236c9cce4596957faaf2b57a3aa1dce65f31f01aa41669aef07370 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ea4b83f2d9ab378b402315c2f3747f9c4a93f6e1ca57bff2965ab4f8ed1568a1 [INFO] running `Command { std: "docker" "start" "-a" "ea4b83f2d9ab378b402315c2f3747f9c4a93f6e1ca57bff2965ab4f8ed1568a1", kill_on_drop: false }` [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling tracing-mock v0.1.0-beta.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.99s [INFO] running `Command { std: "docker" "inspect" "ea4b83f2d9ab378b402315c2f3747f9c4a93f6e1ca57bff2965ab4f8ed1568a1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ea4b83f2d9ab378b402315c2f3747f9c4a93f6e1ca57bff2965ab4f8ed1568a1", kill_on_drop: false }` [INFO] [stdout] ea4b83f2d9ab378b402315c2f3747f9c4a93f6e1ca57bff2965ab4f8ed1568a1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] bcf5150cb693576996f97d485ecf1f6aa35ec40596027ad555eed422797244b2 [INFO] running `Command { std: "docker" "start" "-a" "bcf5150cb693576996f97d485ecf1f6aa35ec40596027ad555eed422797244b2", kill_on_drop: false }` [INFO] [stderr] Compiling tracing-mock v0.1.0-beta.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.44s [INFO] running `Command { std: "docker" "inspect" "bcf5150cb693576996f97d485ecf1f6aa35ec40596027ad555eed422797244b2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "bcf5150cb693576996f97d485ecf1f6aa35ec40596027ad555eed422797244b2", kill_on_drop: false }` [INFO] [stdout] bcf5150cb693576996f97d485ecf1f6aa35ec40596027ad555eed422797244b2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 38a3f85a62d4261f2f0cef25d4289d48c706212362e8fe8b6516f62359c360c4 [INFO] running `Command { std: "docker" "start" "-a" "38a3f85a62d4261f2f0cef25d4289d48c706212362e8fe8b6516f62359c360c4", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tracing_mock-18c14922c801046a) [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/event_ancestry.rs (/opt/rustwide/target/debug/deps/event_ancestry-4be3c8b7c3081662) [INFO] [stdout] [INFO] [stdout] running 23 tests [INFO] [stdout] test contextual_root ... ok [INFO] [stdout] test contextual_parent ... ok [INFO] [stdout] test expect_contextual_parent_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_root - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_root - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_level - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_parent - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_name - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_root - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_id - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_explicit_root - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test explicit_parent ... ok [INFO] [stdout] test explicit_root ... ok [INFO] [stdout] test explicit_parent_wrong_id - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_root - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_level - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_name - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test explicit_and_contextual_root_is_explicit ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s [INFO] [stdout] [INFO] [stderr] Running tests/span_ancestry.rs (/opt/rustwide/target/debug/deps/span_ancestry-77851229dbdc1af1) [INFO] [stdout] [INFO] [stdout] running 23 tests [INFO] [stdout] test contextual_parent ... ok [INFO] [stdout] test contextual_root ... ok [INFO] [stdout] test contextual_parent_wrong_level - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_id - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_name - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_root - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_root - should panic ... ok [INFO] [stdout] test explicit_and_contextual_root_is_explicit ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_explicit_root - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test explicit_parent ... ok [INFO] [stdout] test explicit_parent_wrong_id - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_name - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_level - should panic ... ok [INFO] [stdout] test explicit_root ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stderr] Doc-tests tracing_mock [INFO] [stdout] [INFO] [stdout] running 85 tests [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 300) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 273) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 346) ... ok [INFO] [stdout] test src/event.rs - event (line 10) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::at_level (line 152) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 322) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 370) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 392) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_fields (line 96) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::at_level (line 173) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_target (line 206) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_target (line 226) ... ok [INFO] [stdout] test src/expect.rs - expect (line 8) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_fields (line 116) ... ok [INFO] [stdout] test src/expect.rs - expect::msg (line 204) ... ok [INFO] [stdout] test src/expect.rs - expect::field (line 148) ... ok [INFO] [stdout] test src/expect.rs - expect::event (line 72) ... ok [INFO] [stdout] test src/expect.rs - expect::field (line 167) ... ok [INFO] [stdout] test src/expect.rs - expect::event (line 55) ... ok [INFO] [stdout] test src/expect.rs - expect::msg (line 223) ... ok [INFO] [stdout] test src/expect.rs - expect::span (line 100) ... ok [INFO] [stdout] test src/expect.rs - expect::span (line 119) ... ok [INFO] [stdout] test src/field.rs - field (line 36) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::only (line 276) ... ok [INFO] [stdout] test src/field.rs - field (line 16) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::and (line 211) ... ok [INFO] [stdout] test src/field.rs - field (line 65) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::only (line 294) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::and (line 236) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::with_value (line 180) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::and (line 340) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::only (line 437) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::with_value (line 161) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::only (line 410) ... ok [INFO] [stdout] test src/lib.rs - (line 103) ... ok [INFO] [stdout] test src/span.rs - span (line 13) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::and (line 368) ... ok [INFO] [stdout] test src/span.rs - span (line 53) ... ok [INFO] [stdout] test src/lib.rs - (line 73) ... ok [INFO] [stdout] test src/span.rs - span (line 37) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::at_level (line 437) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::named (line 238) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::at_level (line 458) ... ok [INFO] [stdout] test src/span.rs - span (line 88) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::named (line 259) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::named (line 276) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 635) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 593) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 565) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 614) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 660) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_fields (line 754) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_fields (line 735) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_id (line 328) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 681) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_id (line 361) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_id (line 391) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber (line 34) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_target (line 492) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber (line 10) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_target (line 512) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber (line 77) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockHandle::assert_finished (line 1331) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::enter (line 490) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::clone_span (line 617) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::enter (line 467) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::clone_span (line 596) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::event (line 353) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::event (line 369) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::exit (line 556) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::exit (line 534) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::follows_from (line 703) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::follows_from (line 679) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::new_span (line 407) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::run (line 944) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::only (line 893) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::only (line 911) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::record (line 749) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::named (line 298) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::new_span (line 428) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::run_with_handle (line 968) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::with_filter (line 814) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::with_max_level_hint (line 857) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::record (line 773) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::mock (line 220) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 85 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 20.73s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "38a3f85a62d4261f2f0cef25d4289d48c706212362e8fe8b6516f62359c360c4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "38a3f85a62d4261f2f0cef25d4289d48c706212362e8fe8b6516f62359c360c4", kill_on_drop: false }` [INFO] [stdout] 38a3f85a62d4261f2f0cef25d4289d48c706212362e8fe8b6516f62359c360c4