[INFO] cloning repository https://github.com/rbeneyton/megafine
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rbeneyton/megafine" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frbeneyton%2Fmegafine", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frbeneyton%2Fmegafine'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7c3a9916b83f68a39ff96040bcdc68a1190dfb21
[INFO] testing rbeneyton/megafine against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frbeneyton%2Fmegafine" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/rbeneyton/megafine
[INFO] finished tweaking git repo https://github.com/rbeneyton/megafine
[INFO] tweaked toml for git repo https://github.com/rbeneyton/megafine written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/rbeneyton/megafine 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 git repo https://github.com/rbeneyton/megafine 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] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:37:12
[INFO] [stderr]    |
[INFO] [stderr] 37 | homepage = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 42 | repository = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |              ---------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `megafine` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef
[INFO] running `Command { std: "docker" "start" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", 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" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef" "/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" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", 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" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:37:12
[INFO] [stderr]    |
[INFO] [stderr] 37 | homepage = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 42 | repository = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |              ---------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `megafine` (manifest) generated 1 warning
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling cfg_aliases v0.2.1
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling anyhow v1.0.103
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling colored v3.1.1
[INFO] [stderr]    Compiling shell-words v1.1.1
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling flume v0.12.0
[INFO] [stderr]    Compiling regex-automata v0.4.15
[INFO] [stderr]    Compiling ctrlc v3.5.2
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling clap_complete v4.6.7
[INFO] [stderr]    Compiling megafine v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 42.70s
[INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: nix v0.31.3
[INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4`
[INFO] running `Command { std: "docker" "inspect" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", 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" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef" "/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] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:37:12
[INFO] [stderr]    |
[INFO] [stderr] 37 | homepage = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 42 | repository = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |              ---------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `megafine` (manifest) generated 1 warning
[INFO] [stderr]    Compiling megafine v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 6.85s
[INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: nix v0.31.3
[INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4`
[INFO] running `Command { std: "docker" "inspect" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", 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" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:37:12
[INFO] [stderr]    |
[INFO] [stderr] 37 | homepage = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 42 | repository = "https://github.com/rbeneyton/megafine"
[INFO] [stderr]    |              ---------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `megafine` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.10s
[INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: nix v0.31.3
[INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4`
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/build/megafine/250e0f934d42bed7/out/megafine-250e0f934d42bed7)
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test format::tests::counters_flag_the_perf_cell_in_bold ... ok
[INFO] [stdout] test command::tests::auto_names_strip_common_prefix_and_suffix ... ok
[INFO] [stdout] test format::tests::counters_relative_alignment ... ok
[INFO] [stdout] test executor::tests::partition_groups_are_equal_sized ... ok
[INFO] [stdout] test executor::tests::partition_reserve_plus_leftover ... ok
[INFO] [stdout] test format::tests::count_formatting ... ok
[INFO] [stdout] test format::tests::byte_formatting ... ok
[INFO] [stdout] test command::tests::auto_names_fall_back_to_the_command ... ok
[INFO] [stdout] test format::tests::counters_center_column_is_always_time ... ok
[INFO] [stdout] test format::tests::duration_formatting ... ok
[INFO] [stdout] test format::tests::truncate_multibyte ... ok
[INFO] [stdout] test format::tests::truncate_short_unchanged ... ok
[INFO] [stdout] test format::tests::truncate_tiny_budget ... ok
[INFO] [stdout] test measurement::tests::compute_on_rss_metric ... ok
[INFO] [stdout] test measurement::tests::metric_parse_all_names ... ok
[INFO] [stdout] test format::tests::truncate_keeps_front_and_back ... ok
[INFO] [stdout] test format::tests::auto_unit_boundaries ... ok
[INFO] [stdout] test executor::tests::partition_even_split ... ok
[INFO] [stdout] test executor::tests::partition_natural_leftover ... ok
[INFO] [stdout] test executor::tests::partition_with_reserve ... ok
[INFO] [stdout] test format::tests::counters_relative_column ... ok
[INFO] [stdout] test format::tests::counters_flag_the_metric_cell_in_bold ... ok
[INFO] [stdout] test measurement::tests::single_command_has_unit_ratio_and_no_stddev ... ok
[INFO] [stdout] test format::tests::parse_time_unit ... ok
[INFO] [stdout] test options::tests::default_jobs_minus_reserved ... ok
[INFO] [stdout] test options::tests::counter_metric_auto_enables_counters ... ok
[INFO] [stdout] test options::tests::explicit_jobs ... ok
[INFO] [stdout] test options::tests::defaults ... ok
[INFO] [stdout] test format::tests::time_formatting ... ok
[INFO] [stdout] test options::tests::estimator_defaults_to_mean ... ok
[INFO] [stdout] test format::tests::counters_without_relative_have_no_column ... ok
[INFO] [stdout] test measurement::tests::reference_out_of_range_is_none ... ok
[INFO] [stdout] test measurement::tests::zero_reference_mean_is_none ... ok
[INFO] [stdout] test options::tests::estimator_parsing ... ok
[INFO] [stdout] test options::tests::ignore_failure_plumbs ... ok
[INFO] [stdout] test options::tests::runs_zero_rejected ... ok
[INFO] [stdout] test options::tests::metric_defaults_and_parsing ... ok
[INFO] [stdout] test options::tests::no_pin_conflicts_with_pin_reserved ... ok
[INFO] [stdout] test options::tests::output_input_parsing ... ok
[INFO] [stdout] test options::tests::reference_validation ... ok
[INFO] [stdout] test options::tests::schedule_fair_conflicts_with_target ... ok
[INFO] [stdout] test options::tests::schedule_parsing ... ok
[INFO] [stdout] test format::tests::metric_formatting_per_kind ... ok
[INFO] [stdout] test measurement::tests::ratios_against_first ... ok
[INFO] [stdout] test measurement::tests::metric_value_extraction ... ok
[INFO] [stdout] test measurement::tests::percentile_estimator_changes_ratio ... ok
[INFO] [stdout] test measurement::tests::ratios_against_chosen_reference ... ok
[INFO] [stdout] test command::tests::auto_names_prefix_and_suffix_never_overlap ... ok
[INFO] [stdout] test parameter::tests::list_cross_product ... ok
[INFO] [stdout] test options::tests::too_many_command_names ... ok
[INFO] [stdout] test parameter::tests::list_file_errors ... ok
[INFO] [stdout] test parameter::tests::scan_step_log_requires_step_n ... ok
[INFO] [stdout] test options::tests::target_validation ... ok
[INFO] [stdout] test parameter::tests::scan_step_n_conflicts_with_step_size ... ok
[INFO] [stdout] test parameter::tests::list_from_file ... ok
[INFO] [stdout] test options::tests::sort_parsing ... ok
[INFO] [stdout] test parameter::tests::names_are_substituted ... ok
[INFO] [stdout] test parameter::tests::scan_decimal_step ... ok
[INFO] [stdout] test options::tests::timeout_validation ... ok
[INFO] [stdout] test parameter::tests::scan_integers ... ok
[INFO] [stdout] test options::tests::schedule_sequential_forces_one_job ... ok
[INFO] [stdout] test parameter::tests::scan_step_log_needs_positive_min ... ok
[INFO] [stdout] test parameter::tests::scan_step_log_is_geometric ... ok
[INFO] [stdout] test parameter::tests::shell_braces_pass_through ... ok
[INFO] [stdout] test parameter::tests::unknown_placeholder_errors ... ok
[INFO] [stdout] test stats::tests::estimator_parse ... ok
[INFO] [stdout] test scheduler::tests::pick_exclusive_skips_in_flight_commands ... ok
[INFO] [stdout] test scheduler::tests::pick_fair_dispatches_once_per_round ... ok
[INFO] [stdout] test parameter::tests::single_value_needs_trailing_comma ... ok
[INFO] [stdout] test parameter::tests::trailing_comma_allows_single_value ... ok
[INFO] [stdout] test parameter::tests::scan_step_n_endpoints_are_exact ... ok
[INFO] [stdout] test stats::tests::estimator_labels ... ok
[INFO] [stdout] test parameter::tests::scan_step_n_errors ... ok
[INFO] [stdout] test parameter::tests::commands_vary_slowest ... ok
[INFO] [stdout] test parameter::tests::invalid_ranges_error ... ok
[INFO] [stdout] test parameter::tests::scan_step_n_fractional_keeps_exact_values ... ok
[INFO] [stdout] test parameter::tests::scan_step_n_integer ... ok
[INFO] [stdout] test stats::tests::t_test_p_known_values ... ok
[INFO] [stderr]      Running unittests instrument/megafine-region-rs.rs (/opt/rustwide/target/release/build/megafine/5dfb663559076039/out/megafine_region_rs-5dfb663559076039)
[INFO] [stdout] test stats::tests::mean_of_values ... ok
[INFO] [stdout] test stats::tests::min_max ... ok
[INFO] [stdout] test stats::tests::stddev_known_set ... ok
[INFO] [stdout] test stats::tests::stddev_needs_two_values ... ok
[INFO] [stdout] test stats::tests::welch_known_values ... ok
[INFO] [stdout] test options::tests::raw_needs_two_commands ... ok
[INFO] [stdout] test stats::tests::percentile_interpolates ... ok
[INFO] [stdout] test stats::tests::ratio_stddev_propagation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli.rs (/opt/rustwide/target/release/build/megafine/7048a624e94686d3/out/cli-7048a624e94686d3)
[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] [stdout] 
[INFO] [stdout] running 58 tests
[INFO] [stdout] test counters_reported_when_supported ... ok
[INFO] [stdout] test ignore_failure_counts_mixed_runs ... ok
[INFO] [stdout] test completions_emit_a_script ... ok
[INFO] [stdout] test failing_command_exit_code_is_mirrored ... ok
[INFO] [stdout] test failing_command_errors ... ok
[INFO] [stdout] test ignore_failure_keeps_hooks_strict ... FAILED
[INFO] [stdout] test invalid_estimator_errors ... ok
[INFO] [stdout] test metric_bogus_errors ... ok
[INFO] [stdout] test metric_instructions_needs_perf ... ok
[INFO] [stdout] test missing_input_file_errors_before_running ... ok
[INFO] [stdout] test failing_run_dumps_partial_results ... ok
[INFO] [stdout] test no_command_still_errors ... ok
[INFO] [stdout] test ignore_failure_keeps_failing_runs ... ok
[INFO] [stdout] test conclude_runs_after_each_run_with_its_run_id ... ok
[INFO] [stdout] test parameter_step_log_requires_step_n ... ok
[INFO] [stdout] test failing_run_keeps_raw_stdout_empty ... ok
[INFO] [stdout] test estimator_labels_the_time ... ok
[INFO] [stdout] test command_name_is_shown ... ok
[INFO] [stdout] test output_inherit_shows_child_stdout ... ok
[INFO] [stdout] test bare_command_name_derives_names ... ok
[INFO] [stdout] test parameter_step_n_conflicts_with_step_size ... ok
[INFO] [stdout] test basic_run ... ok
[INFO] [stdout] test parameter_step_log_spaces_geometrically ... ok
[INFO] [stdout] test input_file_feeds_stdin ... ok
[INFO] [stdout] test raw_with_one_command_errors ... ok
[INFO] [stdout] test reference_out_of_range_errors ... ok
[INFO] [stdout] test parameter_step_n_expands_commands ... ok
[INFO] [stdout] test parameter_list_expands_commands ... ok
[INFO] [stdout] test runs_zero_errors ... ok
[INFO] [stdout] test prepare_shares_run_id_with_its_run ... ok
[INFO] [stdout] test schedule_bogus_errors ... ok
[INFO] [stdout] test raw_metric_prints_one_ratio_per_command ... ok
[INFO] [stdout] test output_file_captures_stdout ... ok
[INFO] [stdout] test raw_outputs_only_ratios ... ok
[INFO] [stdout] test metric_rss_ranks_and_shows_metric_line ... ok
[INFO] [stdout] test schedule_sequential_conflicts_with_jobs ... ok
[INFO] [stdout] test sort_bogus_errors ... ok
[INFO] [stdout] test run_id_is_unique_and_incrementing ... ok
[INFO] [stdout] test schedule_sequential_interleaves_commands ... ok
[INFO] [stdout] test metric_user_smoke ... ok
[INFO] [stdout] test clear_difference_has_no_significance_note ... ok
[INFO] [stdout] test reads_commands_from_stdin ... ok
[INFO] [stdout] test raw_reference_picks_baseline ... ok
[INFO] [stdout] test rusage_extras_shown ... ok
[INFO] [stdout] test unknown_parameter_errors ... ok
[INFO] [stdout] test timeout_zero_errors ... ok
[INFO] [stdout] test precision_controls_decimals ... ok
[INFO] [stdout] test parameter_list_reads_values_from_file ... ok
[INFO] [stdout] test reference_marks_chosen_command ... ok
[INFO] [stdout] test region_mode_reports_a_benchmark ... ok
[INFO] [stdout] test jobs_and_warmup_smoke ... ok
[INFO] [stdout] test sort_metric_puts_best_first ... ok
[INFO] [stdout] test two_commands_show_ranking ... ok
[INFO] [stdout] test timeout_kills_the_process_group ... ok
[INFO] [stdout] test schedule_exclusive_serializes_each_command ... ok
[INFO] [stdout] test timeout_with_ignore_failure_keeps_runs ... ok
[INFO] [stdout] test schedule_fair_smoke ... ok
[INFO] [stdout] test target_stops_on_its_own ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- ignore_failure_keeps_hooks_strict stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'ignore_failure_keeps_hooks_strict' (1048) panicked at tests/cli.rs:691:5:
[INFO] [stdout] stderr: Error: the prepare command of task `sleep 0.01` (0) failed
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     command 'false' terminated with a non-zero exit code (exit status: 1)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<megafine::executor::CommandFailed>>::from
[INFO] [stdout]    1: <megafine::options::Options>::execute
[INFO] [stdout]    2: megafine::scheduler::run_task
[INFO] [stdout]    3: std::sys::backtrace::__rust_begin_short_backtrace::<megafine::scheduler::run_benchmarks::{closure#3}::{closure#0}, ()>
[INFO] [stdout]    4: <std::thread::lifecycle::spawn_unchecked<megafine::scheduler::run_benchmarks::{closure#3}::{closure#0}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]    5: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]    6: <unknown>
[INFO] [stdout]    7: clone
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62612608e6d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6261260a27ba - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x62612609297c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x626126069d06 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x626126086669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x626126021f90 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x626126086822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x626126069db2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x626126062889 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62612606a9bd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6261260a2eec - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x62612600dab6 - <cli[138fc321bcb7c939]::ignore_failure_keeps_hooks_strict::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   12:     0x6261260153db - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   13:     0x6261260225b5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   14:     0x62612601bea4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   15:     0x626126025642 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   16:     0x62612608d9ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   17:     0x7683b4b3cdfa - <unknown>
[INFO] [stdout]   18:     0x7683b4bd03d4 - clone
[INFO] [stdout]   19:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- target_stops_on_its_own stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'target_stops_on_its_own' (1769) panicked at tests/cli.rs:188:5:
[INFO] [stdout] stdout: Benchmark 1: sleep 0.01
[INFO] [stdout]   Time (mean ± σ):  23.395 ms ± 33.074 ms    [User: 1.163 ms, System: 3.739 ms, Peak: 7.8 MB]
[INFO] [stdout]   Range (min … max):  15.003 ms … 204.152 ms    32 runs
[INFO] [stdout]   Faults (maj/min):  0 / 456    CtxSw (vol/invol):  2 / 2
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62612608e6d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6261260a27ba - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x62612609297c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x626126069d06 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x626126086669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x626126021f90 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x626126086822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x626126069db2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x626126062889 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62612606a9bd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6261260a2eec - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x626126009b4e - <cli[138fc321bcb7c939]::target_stops_on_its_own::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   12:     0x6261260153db - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   13:     0x6261260225b5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   14:     0x62612601bea4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   15:     0x626126025642 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   16:     0x62612608d9ef - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   17:     0x7683b4b3cdfa - <unknown>
[INFO] [stdout]   18:     0x7683b4bd03d4 - clone
[INFO] [stdout]   19:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     ignore_failure_keeps_hooks_strict
[INFO] [stdout]     target_stops_on_its_own
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 56 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.96s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test cli`
[INFO] running `Command { std: "docker" "inspect" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef", kill_on_drop: false }`
[INFO] [stdout] 48d826edcbb7cb3ec64a3dd8a9367353a7c8c7725baae710f1bc9abf7eb9b3ef
