[INFO] fetching crate corescout-analysis 1.2.0...
[INFO] testing corescout-analysis-1.2.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate corescout-analysis 1.2.0 into /workspace/builds/worker-4-tc2/source
[INFO] started tweaking crates.io crate corescout-analysis 1.2.0
[INFO] finished tweaking crates.io crate corescout-analysis 1.2.0
[INFO] tweaked toml for crates.io crate corescout-analysis 1.2.0 written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate corescout-analysis 1.2.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate corescout-analysis 1.2.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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5
[INFO] running `Command { std: "docker" "start" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling corescout-core v1.2.0
[INFO] [stderr]    Compiling corescout-mirror v1.2.0
[INFO] [stderr]    Compiling corescout-intent v1.2.0
[INFO] [stderr]    Compiling corescout-agency v1.2.0
[INFO] [stderr]    Compiling corescout-substrate v1.2.0
[INFO] [stderr]    Compiling corescout-experiment v1.2.0
[INFO] [stderr]    Compiling corescout-analysis v1.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 35.85s
[INFO] running `Command { std: "docker" "inspect" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling corescout-analysis v1.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 8.28s
[INFO] running `Command { std: "docker" "inspect" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/corescout-analysis/59fbd6a1344aaa2c/out/corescout_analysis-59fbd6a1344aaa2c)
[INFO] [stdout] 
[INFO] [stdout] running 28 tests
[INFO] [stdout] test placement::tests::a_pair_selection_contains_two_cpus ... ok
[INFO] [stdout] test placement::tests::an_explanation_names_the_program_the_cpu_and_the_profile ... ok
[INFO] [stdout] test placement::tests::an_explanation_says_pinning_is_not_universally_good ... ok
[INFO] [stdout] test placement::tests::every_profile_has_a_distinct_label_that_parses_back ... ok
[INFO] [stdout] test placement::tests::profile_names_and_their_aliases_parse ... ok
[INFO] [stdout] test placement::tests::the_latency_profile_selects_the_core_with_the_better_tail ... ok
[INFO] [stdout] test profile_cache::tests::cache_dir_honours_xdg ... ok
[INFO] [stdout] test profile_cache::tests::fingerprint_changes_when_the_machine_does ... ok
[INFO] [stdout] test profile_cache::tests::fingerprint_is_stable_for_the_same_machine ... ok
[INFO] [stdout] test profile_cache::tests::missing_profile_is_a_miss_not_an_error ... ok
[INFO] [stdout] test placement::tests::a_missing_recommendation_is_an_error_rather_than_a_guess ... ok
[INFO] [stdout] test score::tests::near_identical_cores_score_near_identically ... ok
[INFO] [stdout] test score::tests::ranking_is_stable_for_ties ... ok
[INFO] [stdout] test tests::analysis_round_trips_through_json ... ok
[INFO] [stdout] test tests::empty_measurements_are_rejected ... ok
[INFO] [stdout] test tests::fastest_core_wins_compute_and_scores_100 ... ok
[INFO] [stdout] test tests::geometric_mean_behaves ... ok
[INFO] [stdout] test tests::memory_ranking_is_independent_of_compute ... ok
[INFO] [stdout] test tests::quietest_core_wins_jitter_even_though_it_is_slower ... ok
[INFO] [stdout] test tests::recommendations_name_the_smt_sibling_to_keep_idle ... ok
[INFO] [stdout] test tests::stability_reflects_contamination ... ok
[INFO] [stdout] test tests::worker_pair_uses_two_distinct_physical_cores ... ok
[INFO] [stdout] test score::tests::failed_measurements_are_dropped_not_zeroed ... ok
[INFO] [stdout] test score::tests::higher_is_better_puts_the_maximum_at_100 ... ok
[INFO] [stdout] test profile_cache::tests::corrupt_or_foreign_profile_is_a_miss ... ok
[INFO] [stdout] test score::tests::empty_input_yields_empty_scores ... ok
[INFO] [stdout] test score::tests::lower_is_better_puts_the_minimum_at_100 ... ok
[INFO] [stdout] test score::tests::weights_renormalise_around_missing_components ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 28 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests corescout_analysis
[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] running `Command { std: "docker" "inspect" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5", kill_on_drop: false }`
[INFO] [stdout] a73052f8b5bfaeffc11f2c6a4c164245bd7f6d68384e70dcb2d7b5a30d407af5
