[INFO] fetching crate tracing-capture 0.2.0-beta.1... [INFO] testing tracing-capture-0.2.0-beta.1 against master#1ddedbaa5919b7b3e70d984660e21e844c615c97 for pr-128400 [INFO] extracting crate tracing-capture 0.2.0-beta.1 into /workspace/builds/worker-5-tc1/source [INFO] validating manifest of crates.io crate tracing-capture 0.2.0-beta.1 on toolchain 1ddedbaa5919b7b3e70d984660e21e844c615c97 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate tracing-capture 0.2.0-beta.1 [INFO] finished tweaking crates.io crate tracing-capture 0.2.0-beta.1 [INFO] tweaked toml for crates.io crate tracing-capture 0.2.0-beta.1 written to /workspace/builds/worker-5-tc1/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 60 packages to latest compatible versions [INFO] [stderr] Adding idna v0.5.0 (latest: v1.0.2) [INFO] [stderr] Adding nu-ansi-term v0.46.0 (latest: v0.50.1) [INFO] [stderr] Adding pulldown-cmark v0.9.6 (latest: v0.11.0) [INFO] [stderr] Adding toml v0.7.8 (latest: v0.8.19) [INFO] [stderr] Adding toml_edit v0.19.15 (latest: v0.22.20) [INFO] [stderr] Adding winnow v0.5.40 (latest: v0.6.18) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded tracing-tunnel v0.2.0-beta.1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 660ef66ef7389d17a6a0fcfc3f3c7cbd76549888db210acf67fcdd88d454760e [INFO] running `Command { std: "docker" "start" "-a" "660ef66ef7389d17a6a0fcfc3f3c7cbd76549888db210acf67fcdd88d454760e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "660ef66ef7389d17a6a0fcfc3f3c7cbd76549888db210acf67fcdd88d454760e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "660ef66ef7389d17a6a0fcfc3f3c7cbd76549888db210acf67fcdd88d454760e", kill_on_drop: false }` [INFO] [stdout] 660ef66ef7389d17a6a0fcfc3f3c7cbd76549888db210acf67fcdd88d454760e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=forbid" "-e" "RUSTDOCFLAGS=--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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ced88633bc25a0c731fd23809cbe9603fb9f343612255883d64e1769cd3cc084 [INFO] running `Command { std: "docker" "start" "-a" "ced88633bc25a0c731fd23809cbe9603fb9f343612255883d64e1769cd3cc084", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.86 [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling predicates-core v1.0.8 [INFO] [stderr] Compiling anstyle v1.0.8 [INFO] [stderr] Compiling id-arena v2.2.1 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling predicates v3.1.2 [INFO] [stderr] Compiling quote v1.0.36 [INFO] [stderr] Compiling syn v2.0.72 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling tracing-tunnel v0.2.0-beta.1 [INFO] [stderr] Compiling tracing-capture v0.2.0-beta.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.30s [INFO] running `Command { std: "docker" "inspect" "ced88633bc25a0c731fd23809cbe9603fb9f343612255883d64e1769cd3cc084", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ced88633bc25a0c731fd23809cbe9603fb9f343612255883d64e1769cd3cc084", kill_on_drop: false }` [INFO] [stdout] ced88633bc25a0c731fd23809cbe9603fb9f343612255883d64e1769cd3cc084 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=forbid" "-e" "RUSTDOCFLAGS=--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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3f99b818b479aff6ab24fbb210cca600cc34b60051b9874505c417db5024f93e [INFO] running `Command { std: "docker" "start" "-a" "3f99b818b479aff6ab24fbb210cca600cc34b60051b9874505c417db5024f93e", kill_on_drop: false }` [INFO] [stderr] Compiling unicode-ident v1.0.12 [INFO] [stderr] Compiling proc-macro2 v1.0.86 [INFO] [stderr] Compiling winnow v0.5.40 [INFO] [stderr] Compiling pulldown-cmark v0.9.6 [INFO] [stderr] Compiling unicase v2.7.0 [INFO] [stderr] Compiling regex-syntax v0.8.4 [INFO] [stderr] Compiling semver v1.0.23 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling assert_matches v1.5.0 [INFO] [stderr] Compiling quote v1.0.36 [INFO] [stderr] Compiling syn v2.0.72 [INFO] [stderr] Compiling regex-automata v0.4.7 [INFO] [stderr] Compiling regex v1.10.6 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling tracing-attributes v0.1.27 [INFO] [stderr] Compiling tracing v0.1.40 [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling toml_datetime v0.6.8 [INFO] [stderr] Compiling serde_spanned v0.6.7 [INFO] [stderr] Compiling tracing-tunnel v0.2.0-beta.1 [INFO] [stderr] Compiling toml_edit v0.19.15 [INFO] [stderr] Compiling tracing-capture v0.2.0-beta.1 (/opt/rustwide/workdir) [INFO] [stderr] Compiling toml v0.7.8 [INFO] [stderr] Compiling version-sync v0.9.5 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 10.48s [INFO] running `Command { std: "docker" "inspect" "3f99b818b479aff6ab24fbb210cca600cc34b60051b9874505c417db5024f93e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3f99b818b479aff6ab24fbb210cca600cc34b60051b9874505c417db5024f93e", kill_on_drop: false }` [INFO] [stdout] 3f99b818b479aff6ab24fbb210cca600cc34b60051b9874505c417db5024f93e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=forbid" "-e" "RUSTDOCFLAGS=--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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 244e8b6b237207c8908b782fb70d89f14418a461178a80a0a80aaf4de53a9e2a [INFO] running `Command { std: "docker" "start" "-a" "244e8b6b237207c8908b782fb70d89f14418a461178a80a0a80aaf4de53a9e2a", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tracing_capture-a73c6550169dba44) [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test predicates::tests::compound_predicates ... ok [INFO] [stdout] test predicates::tests::level_predicates ... ok [INFO] [stdout] test predicates::tests::message_predicates ... ok [INFO] [stdout] test predicates::tests::name_predicates ... ok [INFO] [stdout] test predicates::tests::using_extensions ... ok [INFO] [stdout] test predicates::tests::compound_predicates_combining_and_or ... ok [INFO] [stdout] test predicates::tests::target_predicates ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/integration/main.rs (/opt/rustwide/target/debug/deps/integration-e58683c54f0c3b19) [INFO] [stdout] [INFO] [stdout] running 12 tests [INFO] [stdout] test capturing_spans_directly ... ok [INFO] [stdout] test explicit_parent_is_correctly_handled ... ok [INFO] [stdout] test capturing_events_with_indirect_ancestor ... ok [INFO] [stdout] test capturing_span_hierarchy ... ok [INFO] [stdout] test recorded_span_values_are_restored ... ok [INFO] [stdout] test capturing_spans_for_replayed_events ... ok [INFO] [stdout] test capturing_wide_span_graph ... ok [INFO] [stdout] test items_from_different_storages_are_not_comparable ... ok [INFO] [stdout] test replayed_spans_are_closed_if_entered_multiple_times ... ok [INFO] [stdout] test spans_are_exited_on_receiver_drop ... ok [INFO] [stdout] test failed_assertion_while_storage_is_locked ... ok [INFO] [stdout] test recording_follows_from_relations ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Running tests/version_match.rs (/opt/rustwide/target/debug/deps/version_match-739db40881a9c640) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test readme_is_in_sync ... ok [INFO] [stdout] test html_root_url_is_in_sync ... 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] Doc-tests tracing_capture [INFO] [stdout] [INFO] [stdout] running 15 tests [INFO] [stdout] test src/lib.rs - CapturedEvent (line 105) ... ok [INFO] [stdout] test src/lib.rs - CapturedSpan (line 243) ... ok [INFO] [stdout] test src/predicates/mod.rs - predicates (line 24) ... ok [INFO] [stdout] test src/predicates/field.rs - predicates::field::value (line 177) ... ok [INFO] [stdout] test src/predicates/mod.rs - predicates::into_fn (line 78) ... ok [INFO] [stdout] test src/predicates/name.rs - predicates::name::name (line 21) ... ok [INFO] [stdout] test src/predicates/target.rs - predicates::target::target (line 85) ... ok [INFO] [stdout] test src/predicates/parent.rs - predicates::parent::ancestor (line 96) ... ok [INFO] [stdout] test src/predicates/field.rs - predicates::field::field (line 63) ... ok [INFO] [stdout] test src/predicates/level.rs - predicates::level::level (line 63) ... ok [INFO] [stdout] test src/predicates/field.rs - predicates::field::message (line 284) ... ok [INFO] [stdout] test src/lib.rs - (line 485) ... ok [INFO] [stdout] test src/predicates/parent.rs - predicates::parent::parent (line 18) ... ok [INFO] [stdout] test src/lib.rs - (line 10) ... ok [INFO] [stdout] test src/lib.rs - (line 454) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.39s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "244e8b6b237207c8908b782fb70d89f14418a461178a80a0a80aaf4de53a9e2a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "244e8b6b237207c8908b782fb70d89f14418a461178a80a0a80aaf4de53a9e2a", kill_on_drop: false }` [INFO] [stdout] 244e8b6b237207c8908b782fb70d89f14418a461178a80a0a80aaf4de53a9e2a [INFO] testing tracing-capture-0.2.0-beta.1 against try#33f1d8bb92813a1bfc23c093c9f443ab09290307 for pr-128400 [INFO] extracting crate tracing-capture 0.2.0-beta.1 into /workspace/builds/worker-5-tc2/source [INFO] validating manifest of crates.io crate tracing-capture 0.2.0-beta.1 on toolchain 33f1d8bb92813a1bfc23c093c9f443ab09290307 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate tracing-capture 0.2.0-beta.1 [INFO] finished tweaking crates.io crate tracing-capture 0.2.0-beta.1 [INFO] tweaked toml for crates.io crate tracing-capture 0.2.0-beta.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 60 packages to latest compatible versions [INFO] [stderr] Adding idna v0.5.0 (latest: v1.0.2) [INFO] [stderr] Adding nu-ansi-term v0.46.0 (latest: v0.50.1) [INFO] [stderr] Adding pulldown-cmark v0.9.6 (latest: v0.11.0) [INFO] [stderr] Adding toml v0.7.8 (latest: v0.8.19) [INFO] [stderr] Adding toml_edit v0.19.15 (latest: v0.22.20) [INFO] [stderr] Adding winnow v0.5.40 (latest: v0.6.18) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] cec1445f91aff3c3b9aac5190ac51ac9b5bbe3e3d44f1d8b0ed67f70506a6527 [INFO] running `Command { std: "docker" "start" "-a" "cec1445f91aff3c3b9aac5190ac51ac9b5bbe3e3d44f1d8b0ed67f70506a6527", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "cec1445f91aff3c3b9aac5190ac51ac9b5bbe3e3d44f1d8b0ed67f70506a6527", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cec1445f91aff3c3b9aac5190ac51ac9b5bbe3e3d44f1d8b0ed67f70506a6527", kill_on_drop: false }` [INFO] [stdout] cec1445f91aff3c3b9aac5190ac51ac9b5bbe3e3d44f1d8b0ed67f70506a6527 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=forbid" "-e" "RUSTDOCFLAGS=--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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 624a70894e45276aa99c07af979b9e263a7d1d0c2f40c2e409801df0605b1120 [INFO] running `Command { std: "docker" "start" "-a" "624a70894e45276aa99c07af979b9e263a7d1d0c2f40c2e409801df0605b1120", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.86 [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling anstyle v1.0.8 [INFO] [stderr] Compiling predicates-core v1.0.8 [INFO] [stderr] Compiling id-arena v2.2.1 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling predicates v3.1.2 [INFO] [stderr] Compiling quote v1.0.36 [INFO] [stderr] Compiling syn v2.0.72 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling tracing-tunnel v0.2.0-beta.1 [INFO] [stderr] Compiling tracing-capture v0.2.0-beta.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.82s [INFO] running `Command { std: "docker" "inspect" "624a70894e45276aa99c07af979b9e263a7d1d0c2f40c2e409801df0605b1120", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "624a70894e45276aa99c07af979b9e263a7d1d0c2f40c2e409801df0605b1120", kill_on_drop: false }` [INFO] [stdout] 624a70894e45276aa99c07af979b9e263a7d1d0c2f40c2e409801df0605b1120 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=forbid" "-e" "RUSTDOCFLAGS=--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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 89bb9519fed87afa558f0c239b7d03e069eef6c9ac7893eee3fa6799af5903e9 [INFO] running `Command { std: "docker" "start" "-a" "89bb9519fed87afa558f0c239b7d03e069eef6c9ac7893eee3fa6799af5903e9", kill_on_drop: false }` [INFO] [stderr] Compiling unicode-ident v1.0.12 [INFO] [stderr] Compiling proc-macro2 v1.0.86 [INFO] [stderr] Compiling regex-syntax v0.8.4 [INFO] [stderr] Compiling pulldown-cmark v0.9.6 [INFO] [stderr] Compiling unicase v2.7.0 [INFO] [stderr] Compiling winnow v0.5.40 [INFO] [stderr] Compiling semver v1.0.23 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling tracing-subscriber v0.3.18 [INFO] [stderr] Compiling assert_matches v1.5.0 [INFO] [stderr] Compiling quote v1.0.36 [INFO] [stderr] Compiling syn v2.0.72 [INFO] [stderr] Compiling regex-automata v0.4.7 [INFO] [stderr] Compiling regex v1.10.6 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling tracing-attributes v0.1.27 [INFO] [stderr] Compiling tracing v0.1.40 [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling serde_spanned v0.6.7 [INFO] [stderr] Compiling toml_datetime v0.6.8 [INFO] [stderr] Compiling tracing-tunnel v0.2.0-beta.1 [INFO] [stderr] Compiling toml_edit v0.19.15 [INFO] [stderr] Compiling tracing-capture v0.2.0-beta.1 (/opt/rustwide/workdir) [INFO] [stderr] Compiling toml v0.7.8 [INFO] [stderr] Compiling version-sync v0.9.5 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 11.18s [INFO] running `Command { std: "docker" "inspect" "89bb9519fed87afa558f0c239b7d03e069eef6c9ac7893eee3fa6799af5903e9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "89bb9519fed87afa558f0c239b7d03e069eef6c9ac7893eee3fa6799af5903e9", kill_on_drop: false }` [INFO] [stdout] 89bb9519fed87afa558f0c239b7d03e069eef6c9ac7893eee3fa6799af5903e9 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=forbid" "-e" "RUSTDOCFLAGS=--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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 857a5d5ac05a2b38028eb92b009eeb995a9128b81861e5d4965d34759f7bce3e [INFO] running `Command { std: "docker" "start" "-a" "857a5d5ac05a2b38028eb92b009eeb995a9128b81861e5d4965d34759f7bce3e", 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_capture-a73c6550169dba44) [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test predicates::tests::compound_predicates ... ok [INFO] [stdout] test predicates::tests::compound_predicates_combining_and_or ... ok [INFO] [stdout] test predicates::tests::message_predicates ... ok [INFO] [stdout] test predicates::tests::level_predicates ... ok [INFO] [stdout] test predicates::tests::target_predicates ... ok [INFO] [stdout] test predicates::tests::name_predicates ... ok [INFO] [stdout] test predicates::tests::using_extensions ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/integration/main.rs (/opt/rustwide/target/debug/deps/integration-e58683c54f0c3b19) [INFO] [stdout] [INFO] [stdout] running 12 tests [INFO] [stdout] test capturing_spans_directly ... ok [INFO] [stdout] test explicit_parent_is_correctly_handled ... ok [INFO] [stdout] test capturing_events_with_indirect_ancestor ... ok [INFO] [stdout] test capturing_span_hierarchy ... ok [INFO] [stdout] test recorded_span_values_are_restored ... ok [INFO] [stdout] test items_from_different_storages_are_not_comparable ... ok [INFO] [stdout] test spans_are_exited_on_receiver_drop ... ok [INFO] [stdout] test capturing_wide_span_graph ... ok [INFO] [stdout] test replayed_spans_are_closed_if_entered_multiple_times ... ok [INFO] [stdout] test recording_follows_from_relations ... ok [INFO] [stdout] test failed_assertion_while_storage_is_locked ... ok [INFO] [stdout] test capturing_spans_for_replayed_events ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- capturing_spans_for_replayed_events stdout ---- [INFO] [stdout] thread 'capturing_spans_for_replayed_events' panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tracing-tunnel-0.2.0-beta.1/src/receiver/mod.rs:542:14: [INFO] [stdout] received bogus tracing event: UnknownMetadataId(94127328155368) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x559bb9f22415 - std::backtrace_rs::backtrace::libunwind::trace::h644651cb29d88bb0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x559bb9f22415 - std::backtrace_rs::backtrace::trace_unsynchronized::h4f227a4714c93f22 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x559bb9f22415 - std::sys::backtrace::_print_fmt::hfe88767e38ff4b4e [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x559bb9f22415 - ::fmt::h0e27af9f1d310e0a [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x559bb9f4a06b - core::fmt::rt::Argument::fmt::h900f10175a69b72e [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x559bb9f4a06b - core::fmt::write::h3a97e76bd9b733fa [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/fmt/mod.rs:1178:21 [INFO] [stdout] 6: 0x559bb9f1f88f - std::io::Write::write_fmt::he4611995baa28da3 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/io/mod.rs:1823:15 [INFO] [stdout] 7: 0x559bb9f23c31 - std::sys::backtrace::BacktraceLock::print::h52f15558f43bfe2f [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x559bb9f23c31 - std::panicking::default_hook::{{closure}}::h77edc81607b06015 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:266:22 [INFO] [stdout] 9: 0x559bb9f237d8 - std::panicking::default_hook::h4a889c281e863e6e [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:290:9 [INFO] [stdout] 10: 0x559bb9e5e95a - as core::ops::function::Fn>::call::h87ae598a6618f03f [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2162:9 [INFO] [stdout] 11: 0x559bb9e5e95a - test::test_main::{{closure}}::haa35c2695dfcc048 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:132:21 [INFO] [stdout] 12: 0x559bb9f2453f - as core::ops::function::Fn>::call::h0934a01d4a1cdb06 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2162:9 [INFO] [stdout] 13: 0x559bb9f2453f - std::panicking::rust_panic_with_hook::h74d8fff54c965152 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:805:13 [INFO] [stdout] 14: 0x559bb9f24167 - std::panicking::begin_panic_handler::{{closure}}::h82b61517a14ab3ae [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:671:13 [INFO] [stdout] 15: 0x559bb9f228d9 - std::sys::backtrace::__rust_end_short_backtrace::h7f24a51d7748d3e4 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x559bb9f23df4 - rust_begin_unwind [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:662:5 [INFO] [stdout] 17: 0x559bb9f47d63 - core::panicking::panic_fmt::h7de3a20d92caa6a2 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panicking.rs:74:14 [INFO] [stdout] 18: 0x559bb9f482b6 - core::result::unwrap_failed::hbfce3c09bc996f11 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/result.rs:1679:5 [INFO] [stdout] 19: 0x559bb9e84034 - core::result::Result::expect::h588eec2b56a8cc23 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/result.rs:1059:23 [INFO] [stdout] 20: 0x559bb9e7fda8 - tracing_tunnel::receiver::TracingEventReceiver::receive::habf38790ee199b08 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tracing-tunnel-0.2.0-beta.1/src/receiver/mod.rs:541:9 [INFO] [stdout] 21: 0x559bb9de004e - integration::capturing_spans_for_replayed_events::{{closure}}::h37c0856eb2a93002 [INFO] [stdout] at /opt/rustwide/workdir/tests/integration/main.rs:250:13 [INFO] [stdout] 22: 0x559bb9e0349a - tracing_core::dispatcher::with_default::h98409f19e7b26e14 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tracing-core-0.1.32/src/dispatcher.rs:265:5 [INFO] [stdout] 23: 0x559bb9e1566a - tracing::subscriber::with_default::h6627fa10bda828fb [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tracing-0.1.40/src/subscriber.rs:24:5 [INFO] [stdout] 24: 0x559bb9ddfd68 - integration::capturing_spans_for_replayed_events::h36419c3292c7daa7 [INFO] [stdout] at /opt/rustwide/workdir/tests/integration/main.rs:247:5 [INFO] [stdout] 25: 0x559bb9ddfc07 - integration::capturing_spans_for_replayed_events::{{closure}}::h54387aaf52f0190b [INFO] [stdout] at /opt/rustwide/workdir/tests/integration/main.rs:242:41 [INFO] [stdout] 26: 0x559bb9dfc096 - core::ops::function::FnOnce::call_once::h0627ebb0c1ccbdb5 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 27: 0x559bb9e634db - core::ops::function::FnOnce::call_once::h24a6f01be65f4680 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 28: 0x559bb9e634db - test::__rust_begin_short_backtrace::h7c53016b80d5a608 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:620:18 [INFO] [stdout] 29: 0x559bb9e62cf5 - test::run_test_in_process::{{closure}}::hde6a274ce897aaad [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:643:60 [INFO] [stdout] 30: 0x559bb9e62cf5 - as core::ops::function::FnOnce<()>>::call_once::hd431ac2f54963786 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 31: 0x559bb9e62cf5 - std::panicking::try::do_call::h10362e01a063baa0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:554:40 [INFO] [stdout] 32: 0x559bb9e62cf5 - std::panicking::try::h30318180e4290dce [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:518:19 [INFO] [stdout] 33: 0x559bb9e62cf5 - std::panic::catch_unwind::h97d52b1b1f400ecc [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panic.rs:345:14 [INFO] [stdout] 34: 0x559bb9e62cf5 - test::run_test_in_process::haec7bb1f728b765d [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:643:27 [INFO] [stdout] 35: 0x559bb9e62cf5 - test::run_test::{{closure}}::ha988a53ee25f80e1 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:564:43 [INFO] [stdout] 36: 0x559bb9e285c4 - test::run_test::{{closure}}::hddc4d91f14475af0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:594:41 [INFO] [stdout] 37: 0x559bb9e285c4 - std::sys::backtrace::__rust_begin_short_backtrace::h550bd80387fd32b0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 38: 0x559bb9e2bcf2 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h0907e6530d89c432 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/thread/mod.rs:524:17 [INFO] [stdout] 39: 0x559bb9e2bcf2 - as core::ops::function::FnOnce<()>>::call_once::h1426168782d20988 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 40: 0x559bb9e2bcf2 - std::panicking::try::do_call::h9e10c7df8de701e0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:554:40 [INFO] [stdout] 41: 0x559bb9e2bcf2 - std::panicking::try::hc0169bb633716dc9 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:518:19 [INFO] [stdout] 42: 0x559bb9e2bcf2 - std::panic::catch_unwind::h2d365a7fbba378ff [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panic.rs:345:14 [INFO] [stdout] 43: 0x559bb9e2bcf2 - std::thread::Builder::spawn_unchecked_::{{closure}}::h99b3d5b0eb8d8349 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/thread/mod.rs:523:30 [INFO] [stdout] 44: 0x559bb9e2bcf2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h785f99837c772588 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 45: 0x559bb9f287ab - as core::ops::function::FnOnce>::call_once::h9d8f2204f4fcbe73 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2148:9 [INFO] [stdout] 46: 0x559bb9f287ab - as core::ops::function::FnOnce>::call_once::h817ffd7b41f007e2 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2148:9 [INFO] [stdout] 47: 0x559bb9f287ab - std::sys::pal::unix::thread::Thread::new::thread_start::hfffda88b0cd3e69f [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 48: 0x7fd1ed1fdac3 - [INFO] [stdout] 49: 0x7fd1ed28ea04 - clone [INFO] [stdout] 50: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] capturing_spans_for_replayed_events [INFO] [stdout] [INFO] [stdout] test result: FAILED. 11 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test integration` [INFO] running `Command { std: "docker" "inspect" "857a5d5ac05a2b38028eb92b009eeb995a9128b81861e5d4965d34759f7bce3e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "857a5d5ac05a2b38028eb92b009eeb995a9128b81861e5d4965d34759f7bce3e", kill_on_drop: false }` [INFO] [stdout] 857a5d5ac05a2b38028eb92b009eeb995a9128b81861e5d4965d34759f7bce3e