[INFO] fetching crate tracing-mock 0.1.0-beta.1... [INFO] testing tracing-mock-0.1.0-beta.1 against beta-2025-09-21 for beta-1.91-3 [INFO] extracting crate tracing-mock 0.1.0-beta.1 into /workspace/builds/worker-6-tc2/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-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate tracing-mock 0.1.0-beta.1 on toolchain beta-2025-09-21 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-09-21" "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" "+beta-2025-09-21" "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" "+beta-2025-09-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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" "+beta-2025-09-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 06a0e45a958a9f504608a287934a94c6453dbd0013db98e9387258059f56c274 [INFO] running `Command { std: "docker" "start" "-a" "06a0e45a958a9f504608a287934a94c6453dbd0013db98e9387258059f56c274", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "06a0e45a958a9f504608a287934a94c6453dbd0013db98e9387258059f56c274", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "06a0e45a958a9f504608a287934a94c6453dbd0013db98e9387258059f56c274", kill_on_drop: false }` [INFO] [stdout] 06a0e45a958a9f504608a287934a94c6453dbd0013db98e9387258059f56c274 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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" "+beta-2025-09-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 04c138f8da80f093d3015abf072ce9eab8ff18613529f44e481c2a89fe55db7a [INFO] running `Command { std: "docker" "start" "-a" "04c138f8da80f093d3015abf072ce9eab8ff18613529f44e481c2a89fe55db7a", 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.69s [INFO] running `Command { std: "docker" "inspect" "04c138f8da80f093d3015abf072ce9eab8ff18613529f44e481c2a89fe55db7a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "04c138f8da80f093d3015abf072ce9eab8ff18613529f44e481c2a89fe55db7a", kill_on_drop: false }` [INFO] [stdout] 04c138f8da80f093d3015abf072ce9eab8ff18613529f44e481c2a89fe55db7a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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" "+beta-2025-09-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 94762afe1083fe9018deee4bac4bdcffca57d2b8050dc732f5148eb3011748f1 [INFO] running `Command { std: "docker" "start" "-a" "94762afe1083fe9018deee4bac4bdcffca57d2b8050dc732f5148eb3011748f1", 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 1.74s [INFO] running `Command { std: "docker" "inspect" "94762afe1083fe9018deee4bac4bdcffca57d2b8050dc732f5148eb3011748f1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "94762afe1083fe9018deee4bac4bdcffca57d2b8050dc732f5148eb3011748f1", kill_on_drop: false }` [INFO] [stdout] 94762afe1083fe9018deee4bac4bdcffca57d2b8050dc732f5148eb3011748f1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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" "+beta-2025-09-21" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 3a8cc255687d3b8d135b2745e5627cbe5afa7f027396d86717f37ba6b17185bd [INFO] running `Command { std: "docker" "start" "-a" "3a8cc255687d3b8d135b2745e5627cbe5afa7f027396d86717f37ba6b17185bd", 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-13bf65a0e7363f88) [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-6c5df3949defd7dc) [INFO] [stdout] [INFO] [stdout] running 23 tests [INFO] [stdout] test contextual_root ... ok [INFO] [stdout] test contextual_parent ... ok [INFO] [stdout] test contextual_parent_wrong_name - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_root - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_level - 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_contextual_parent_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_root - should panic ... ok [INFO] [stdout] test contextual_parent_wrong_id - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_root - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test explicit_and_contextual_root_is_explicit ... ok [INFO] [stdout] test explicit_root ... ok [INFO] [stdout] test explicit_parent_wrong_level - should panic ... ok [INFO] [stderr] Running tests/span_ancestry.rs (/opt/rustwide/target/debug/deps/span_ancestry-eeb9eae78a6e35d8) [INFO] [stdout] test explicit_parent ... ok [INFO] [stdout] test explicit_parent_wrong_name - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_id - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s [INFO] [stdout] [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_root - should panic ... ok [INFO] [stdout] test expect_explicit_parent_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_root - should panic ... ok [INFO] [stdout] test expect_contextual_parent_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_explicit_parent_actual_contextual_parent - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_explicit_root - should panic ... ok [INFO] [stdout] test expect_contextual_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_level - should panic ... ok [INFO] [stdout] test explicit_and_contextual_root_is_explicit ... ok [INFO] [stdout] test explicit_parent ... ok [INFO] [stdout] test explicit_root ... ok [INFO] [stdout] test expect_explicit_root_actual_explicit_parent - should panic ... ok [INFO] [stdout] test expect_explicit_root_actual_contextual_parent - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_name - should panic ... ok [INFO] [stdout] test explicit_parent_wrong_id - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s [INFO] [stdout] [INFO] [stderr] Doc-tests tracing_mock [INFO] [stdout] [INFO] [stdout] running 85 tests [INFO] [stdout] test src/event.rs - event (line 10) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 346) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 322) ... 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 370) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::at_level (line 173) ... ok [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_fields (line 116) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_fields (line 96) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_ancestry (line 392) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_target (line 206) ... ok [INFO] [stdout] test src/expect.rs - expect (line 8) ... ok [INFO] [stdout] test src/event.rs - event::ExpectedEvent::with_target (line 226) ... ok [INFO] [stdout] test src/expect.rs - expect::event (line 72) ... ok [INFO] [stdout] test src/expect.rs - expect::event (line 55) ... 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::span (line 100) ... ok [INFO] [stdout] test src/expect.rs - expect::span (line 119) ... ok [INFO] [stdout] test src/expect.rs - expect::field (line 167) ... ok [INFO] [stdout] test src/field.rs - field (line 16) ... ok [INFO] [stdout] test src/expect.rs - expect::msg (line 223) ... ok [INFO] [stdout] test src/field.rs - field (line 65) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::and (line 211) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::only (line 276) ... 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 (line 36) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::with_value (line 161) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedField::only (line 294) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::and (line 368) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::only (line 437) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::and (line 340) ... ok [INFO] [stdout] test src/span.rs - span (line 13) ... ok [INFO] [stdout] test src/field.rs - field::ExpectedFields::only (line 410) ... ok [INFO] [stdout] test src/span.rs - span (line 53) ... ok [INFO] [stdout] test src/lib.rs - (line 103) ... ok [INFO] [stdout] test src/lib.rs - (line 73) ... ok [INFO] [stdout] test src/span.rs - span (line 88) ... ok [INFO] [stdout] test src/span.rs - span (line 37) ... 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::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::with_ancestry (line 565) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::at_level (line 458) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_ancestry (line 593) ... 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 660) ... 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 614) ... 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 361) ... 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 391) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_fields (line 754) ... 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 (line 34) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_target (line 512) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber (line 10) ... ok [INFO] [stdout] test src/span.rs - span::ExpectedSpan::with_target (line 492) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::clone_span (line 617) ... 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 534) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::enter (line 467) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::follows_from (line 679) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::enter (line 490) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::follows_from (line 703) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::exit (line 556) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::record (line 773) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::named (line 298) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::new_span (line 407) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::record (line 749) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::only (line 893) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::new_span (line 428) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::only (line 911) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::run_with_handle (line 968) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::run (line 944) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::with_max_level_hint (line 857) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::mock (line 220) ... ok [INFO] [stdout] test src/subscriber.rs - subscriber::MockSubscriber::with_filter (line 814) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 85 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 21.23s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "3a8cc255687d3b8d135b2745e5627cbe5afa7f027396d86717f37ba6b17185bd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3a8cc255687d3b8d135b2745e5627cbe5afa7f027396d86717f37ba6b17185bd", kill_on_drop: false }` [INFO] [stdout] 3a8cc255687d3b8d135b2745e5627cbe5afa7f027396d86717f37ba6b17185bd