[INFO] fetching crate invalidation 0.1.0...
[INFO] testing invalidation-0.1.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1
[INFO] extracting crate invalidation 0.1.0 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate invalidation 0.1.0
[INFO] finished tweaking crates.io crate invalidation 0.1.0
[INFO] tweaked toml for crates.io crate invalidation 0.1.0 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate invalidation 0.1.0 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate invalidation 0.1.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f1153e0feb910c3e78fad832fbe12ac574f6c5f246cd7789fbfdc1abba31e719
[INFO] running `Command { std: "docker" "start" "-a" "f1153e0feb910c3e78fad832fbe12ac574f6c5f246cd7789fbfdc1abba31e719", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f1153e0feb910c3e78fad832fbe12ac574f6c5f246cd7789fbfdc1abba31e719", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f1153e0feb910c3e78fad832fbe12ac574f6c5f246cd7789fbfdc1abba31e719", kill_on_drop: false }`
[INFO] [stdout] f1153e0feb910c3e78fad832fbe12ac574f6c5f246cd7789fbfdc1abba31e719
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 62a3b5f62089a8f185f1fbed354d568000587aef259db9f98e065b6d2e9a73f2
[INFO] running `Command { std: "docker" "start" "-a" "62a3b5f62089a8f185f1fbed354d568000587aef259db9f98e065b6d2e9a73f2", kill_on_drop: false }`
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling invalidation v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.15s
[INFO] running `Command { std: "docker" "inspect" "62a3b5f62089a8f185f1fbed354d568000587aef259db9f98e065b6d2e9a73f2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "62a3b5f62089a8f185f1fbed354d568000587aef259db9f98e065b6d2e9a73f2", kill_on_drop: false }`
[INFO] [stdout] 62a3b5f62089a8f185f1fbed354d568000587aef259db9f98e065b6d2e9a73f2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 70b0abbab7143c4ac436beac9ee1b2869781e84c44011ecf58b4e71da221ed90
[INFO] running `Command { std: "docker" "start" "-a" "70b0abbab7143c4ac436beac9ee1b2869781e84c44011ecf58b4e71da221ed90", kill_on_drop: false }`
[INFO] [stderr]    Compiling invalidation v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.41s
[INFO] running `Command { std: "docker" "inspect" "70b0abbab7143c4ac436beac9ee1b2869781e84c44011ecf58b4e71da221ed90", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "70b0abbab7143c4ac436beac9ee1b2869781e84c44011ecf58b4e71da221ed90", kill_on_drop: false }`
[INFO] [stdout] 70b0abbab7143c4ac436beac9ee1b2869781e84c44011ecf58b4e71da221ed90
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6516d87f221c7b5d34cb811ffcee364ac653eb5fc93125e927ac33310f956587
[INFO] running `Command { std: "docker" "start" "-a" "6516d87f221c7b5d34cb811ffcee364ac653eb5fc93125e927ac33310f956587", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/invalidation-be79282511952fa0)
[INFO] [stdout] 
[INFO] [stdout] running 71 tests
[INFO] [stdout] test channel::tests::channel_set_iter_exact_size ... ok
[INFO] [stdout] test channel::tests::channel_set_iter ... ok
[INFO] [stdout] test channel::tests::channel_set_operations ... ok
[INFO] [stdout] test drain::tests::affected_sorted_with_trace_records_one_path ... ok
[INFO] [stdout] test drain::tests::cycles_stall_drain_collect_with_completion ... ok
[INFO] [stdout] test drain::tests::cycles_stall_drain ... ok
[INFO] [stdout] test channel::tests::channel_new_valid ... ok
[INFO] [stdout] test channel::tests::channel_set_bitwise ... ok
[INFO] [stdout] test drain::tests::deterministic_topological_order_diamond_is_total ... ok
[INFO] [stdout] test drain::tests::empty_invalidated_set ... ok
[INFO] [stdout] test drain::tests::drain_affected_sorted_expands_dependents ... ok
[INFO] [stdout] test drain::tests::duplicate_keys_deduplicated ... ok
[INFO] [stdout] test drain::tests::drain_affected_sorted_multiple_roots ... ok
[INFO] [stdout] test drain::tests::drain_sorted_function ... ok
[INFO] [stdout] test drain::tests::no_dependencies ... ok
[INFO] [stdout] test drain::tests::topological_order_diamond ... ok
[INFO] [stdout] test drain::tests::topological_order_chain ... ok
[INFO] [stdout] test drain::tests::size_hint_accurate ... ok
[INFO] [stdout] test drain::tests::partial_invalidated_set ... ok
[INFO] [stdout] test drain_builder::tests::within_dependencies_of_filters_invalidated_only ... ok
[INFO] [stdout] test drain_builder::tests::within_keys_does_not_clear_outside_roots ... ok
[INFO] [stdout] test drain_builder::tests::affected_with_trace_records_one_plausible_path ... ok
[INFO] [stdout] test drain_builder::tests::deterministic_diamond_is_total ... ok
[INFO] [stdout] test graph::tests::channel_independence ... ok
[INFO] [stdout] test graph::tests::cycle_ignore ... ok
[INFO] [stdout] test graph::tests::cycle_detection_error ... ok
[INFO] [stdout] test graph::tests::cycle_allow ... ok
[INFO] [stdout] test graph::tests::dependency_channels ... ok
[INFO] [stdout] test graph::tests::in_out_degree ... ok
[INFO] [stdout] test graph::tests::keys_and_keys_vec_are_unique ... ok
[INFO] [stdout] test graph::tests::remove_dependency ... ok
[INFO] [stdout] test graph::tests::replace_dependencies_is_channel_scoped ... ok
[INFO] [stdout] test graph::tests::remove_key ... ok
[INFO] [stdout] test graph::tests::replace_dependencies_noop_when_set_unchanged_returns_false ... ok
[INFO] [stdout] test graph::tests::replace_dependencies_rolls_back_mixed_delta_on_cycle_error ... ok
[INFO] [stdout] test graph::tests::replace_dependencies_updates_in_place ... ok
[INFO] [stdout] test graph::tests::replace_dependencies_rolls_back_on_cycle_error ... ok
[INFO] [stdout] test graph::tests::self_dependency_is_cycle ... ok
[INFO] [stdout] test graph::tests::transitive_dependents ... ok
[INFO] [stdout] test intern::tests::interns_duplicates_to_same_id ... ok
[INFO] [stdout] test policy::tests::eager_handles_diamond ... ok
[INFO] [stdout] test policy::tests::eager_policy_marks_all_dependents ... ok
[INFO] [stdout] test set::tests::channel_independence ... ok
[INFO] [stdout] test policy::tests::eager_policy_from_middle ... ok
[INFO] [stdout] test policy::tests::lazy_policy_only_marks_key ... ok
[INFO] [stdout] test set::tests::clear_specific_channel ... ok
[INFO] [stdout] test policy::tests::policy_through_reference ... ok
[INFO] [stdout] test set::tests::clear_all ... ok
[INFO] [stdout] test set::tests::drain_clears_channel ... ok
[INFO] [stdout] test set::tests::generation_increments ... ok
[INFO] [stdout] test set::tests::remove_key_from_all_channels ... ok
[INFO] [stdout] test set::tests::len_and_iter ... ok
[INFO] [stdout] test set::tests::mark_and_query ... ok
[INFO] [stdout] test set::tests::take_removes_single_key ... ok
[INFO] [stdout] test trace::tests::can_fill_in_missing_causes_for_already_invalidated_keys ... ok
[INFO] [stdout] test tracker::tests::basic_workflow ... ok
[INFO] [stdout] test trace::tests::records_one_parent_path ... ok
[INFO] [stdout] test tracker::tests::clear_specific_channel ... ok
[INFO] [stdout] test tracker::tests::cycle_handling_modes ... ok
[INFO] [stdout] test tracker::tests::clear_all ... ok
[INFO] [stdout] test tracker::tests::manual_mark_no_propagation ... ok
[INFO] [stdout] test tracker::tests::lazy_policy ... ok
[INFO] [stdout] test tracker::tests::generation_tracking ... ok
[INFO] [stdout] test tracker::tests::multiple_channels ... ok
[INFO] [stdout] test tracker::tests::remove_key ... ok
[INFO] [stdout] test tracker::tests::peek_sorted_preserves_state ... ok
[INFO] [stdout] test tracker::tests::replace_dependencies_uses_configured_cycle_handling ... ok
[INFO] [stdout] test graph::tests::add_and_query_dependencies ... ok
[INFO] [stdout] test channel::tests::channel_new_invalid - should panic ... ok
[INFO] [stdout] test drain::tests::deterministic_drain_rejects_sparse_key_space - should panic ... ok
[INFO] [stdout] test graph::tests::add_dependency_rejects_sparse_key_space - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests invalidation
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test src/channel.rs - channel::Channel (line 17) ... ok
[INFO] [stdout] test src/lib.rs - (line 29) ... ok
[INFO] [stdout] test src/channel.rs - channel::ChannelSet (line 74) ... ok
[INFO] [stdout] test src/drain.rs - drain::drain_sorted (line 476) ... ok
[INFO] [stdout] test src/drain.rs - drain::drain_affected_sorted (line 546) ... ok
[INFO] [stdout] test src/policy.rs - policy::EagerPolicy (line 80) ... ok
[INFO] [stdout] test src/drain.rs - drain::DrainSorted (line 127) ... ok
[INFO] [stdout] test src/graph.rs - graph::InvalidationGraph (line 87) ... ok
[INFO] [stdout] test src/policy.rs - policy::LazyPolicy (line 219) ... ok
[INFO] [stdout] test src/lib.rs - (line 70) ... ok
[INFO] [stdout] test src/intern.rs - intern (line 15) ... ok
[INFO] [stdout] test src/policy.rs - policy::PropagationPolicy (line 24) ... ok
[INFO] [stdout] test src/tracker.rs - tracker::InvalidationTracker<K>::drain_affected_sorted (line 377) ... ok
[INFO] [stdout] test src/tracker.rs - tracker::InvalidationTracker (line 30) ... ok
[INFO] [stdout] test src/scratch.rs - scratch (line 9) ... ok
[INFO] [stdout] test src/trace.rs - trace::OneParentRecorder (line 82) ... ok
[INFO] [stdout] test src/tracker.rs - tracker::InvalidationTracker<K>::drain (line 91) ... ok
[INFO] [stdout] test src/set.rs - set::InvalidationSet (line 28) ... ok
[INFO] [stdout] test src/trace.rs - trace (line 18) ... ok
[INFO] [stdout] test src/tracker.rs - tracker::InvalidationTracker<K>::drain_sorted (line 335) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.04s; merged doctests compilation took 0.98s
[INFO] running `Command { std: "docker" "inspect" "6516d87f221c7b5d34cb811ffcee364ac653eb5fc93125e927ac33310f956587", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6516d87f221c7b5d34cb811ffcee364ac653eb5fc93125e927ac33310f956587", kill_on_drop: false }`
[INFO] [stdout] 6516d87f221c7b5d34cb811ffcee364ac653eb5fc93125e927ac33310f956587