[INFO] cloning repository https://github.com/consistent-milk12/pbench
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/consistent-milk12/pbench" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fconsistent-milk12%2Fpbench", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fconsistent-milk12%2Fpbench'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 3161f4351c46f147091e02f0e3195a14294b32cd
[INFO] testing consistent-milk12/pbench against try#8da20828b6ea601dfd8e2448cb50ace88876bd78 for pr-150933
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fconsistent-milk12%2Fpbench" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-5-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/consistent-milk12/pbench
[INFO] finished tweaking git repo https://github.com/consistent-milk12/pbench
[INFO] tweaked toml for git repo https://github.com/consistent-milk12/pbench written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/consistent-milk12/pbench on toolchain 8da20828b6ea601dfd8e2448cb50ace88876bd78
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/consistent-milk12/pbench 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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 00c533fe9eea3582ce97b1614d76729acc50c65addd2ab715cc275f060d92360
[INFO] running `Command { std: "docker" "start" "-a" "00c533fe9eea3582ce97b1614d76729acc50c65addd2ab715cc275f060d92360", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "00c533fe9eea3582ce97b1614d76729acc50c65addd2ab715cc275f060d92360", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "00c533fe9eea3582ce97b1614d76729acc50c65addd2ab715cc275f060d92360", kill_on_drop: false }`
[INFO] [stdout] 00c533fe9eea3582ce97b1614d76729acc50c65addd2ab715cc275f060d92360
[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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0da2ad50deb37db347b22fb10c2121b92e0c6abee8d5896e2b690dd8a6aad18a
[INFO] running `Command { std: "docker" "start" "-a" "0da2ad50deb37db347b22fb10c2121b92e0c6abee8d5896e2b690dd8a6aad18a", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling base64 v0.21.7
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling hdrhistogram v7.5.4
[INFO] [stderr]    Compiling pbench-macros v0.2.0 (/opt/rustwide/workdir/pbench-macros)
[INFO] [stderr]    Compiling pbench v0.2.0 (/opt/rustwide/workdir/pbench)
[INFO] [stdout] warning: unnecessary `unsafe` block
[INFO] [stdout]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stdout]    |
[INFO] [stdout] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stdout]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.30s
[INFO] running `Command { std: "docker" "inspect" "0da2ad50deb37db347b22fb10c2121b92e0c6abee8d5896e2b690dd8a6aad18a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0da2ad50deb37db347b22fb10c2121b92e0c6abee8d5896e2b690dd8a6aad18a", kill_on_drop: false }`
[INFO] [stdout] 0da2ad50deb37db347b22fb10c2121b92e0c6abee8d5896e2b690dd8a6aad18a
[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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6ee46348d64260cb6c21eed64cae7d66bf6ba22269c739a49c0883ca06c84d2f
[INFO] running `Command { std: "docker" "start" "-a" "6ee46348d64260cb6c21eed64cae7d66bf6ba22269c739a49c0883ca06c84d2f", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling target-triple v1.0.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml_datetime v1.0.0+spec-1.1.0
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling pbench-macros v0.2.0 (/opt/rustwide/workdir/pbench-macros)
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling toml_writer v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling hdrhistogram v7.5.4
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling rustc-hash v2.1.1
[INFO] [stderr]    Compiling saa v5.5.0
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling sdd v4.6.2
[INFO] [stderr]    Compiling termcolor v1.4.1
[INFO] [stderr]    Compiling scc v3.6.5
[INFO] [stderr]    Compiling pbench v0.2.0 (/opt/rustwide/workdir/pbench)
[INFO] [stderr]    Compiling libmimalloc-sys v0.1.44
[INFO] [stdout] warning: unnecessary `unsafe` block
[INFO] [stdout]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stdout]    |
[INFO] [stdout] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stdout]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling seize v0.5.1
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling toml v1.0.2+spec-1.1.0
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling mimalloc v0.1.48
[INFO] [stderr]    Compiling masstree v0.8.1
[INFO] [stderr]    Compiling trybuild v1.0.116
[INFO] [stdout] warning: unnecessary `unsafe` block
[INFO] [stdout]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stdout]    |
[INFO] [stdout] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stdout]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 23.75s
[INFO] running `Command { std: "docker" "inspect" "6ee46348d64260cb6c21eed64cae7d66bf6ba22269c739a49c0883ca06c84d2f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6ee46348d64260cb6c21eed64cae7d66bf6ba22269c739a49c0883ca06c84d2f", kill_on_drop: false }`
[INFO] [stdout] 6ee46348d64260cb6c21eed64cae7d66bf6ba22269c739a49c0883ca06c84d2f
[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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 627f28b01f0ceeaa64debd3ea625da0669749743cce0aeb1b878488f89ff0cd6
[INFO] running `Command { std: "docker" "start" "-a" "627f28b01f0ceeaa64debd3ea625da0669749743cce0aeb1b878488f89ff0cd6", kill_on_drop: false }`
[INFO] [stderr] warning: unnecessary `unsafe` block
[INFO] [stderr]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stderr]    |
[INFO] [stderr] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stderr]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `pbench` (lib) generated 1 warning
[INFO] [stderr] warning: `pbench` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pbench-84d347cf502b6c1e)
[INFO] [stdout] 
[INFO] [stdout] running 241 tests
[INFO] [stdout] test bencher::unit_tests::bench_context_zero_threads_panics - should panic ... ok
[INFO] [stdout] test bencher::unit_tests::adaptive_tuning_produces_stats ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_collects_samples ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_string_output ... ok
[INFO] [stdout] test bencher::unit_tests::bench_with_counter_via_context_finish ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_unit_output ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_i32_output ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_drop_correctness ... ok
[INFO] [stdout] test bencher::unit_tests::context_finish_without_run_returns_none ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_with_one_thread_matches_single ... ok
[INFO] [stdout] test bencher::unit_tests::bench_with_counter ... ok
[INFO] [stdout] test bencher::unit_tests::bench_without_counter_has_no_collection ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_collects_samples ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_propagates_panic - should panic ... ok
[INFO] [stdout] test bencher::unit_tests::counter_with_multi_iteration_samples ... ok
[INFO] [stdout] test bencher::unit_tests::counter_length_matches_actual_sample_count ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_sample_count_is_total_not_per_thread ... ok
[INFO] [stdout] test bencher::unit_tests::counter_overwrite_uses_last_value ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_four_threads ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_drop_correctness ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_counter_length_matches_sample_count ... ok
[INFO] [stdout] test cli::unit_tests::parse_bytes_format ... ok
[INFO] [stdout] test cli::unit_tests::parse_defaults ... ok
[INFO] [stdout] test cli::unit_tests::parse_filter ... ok
[INFO] [stdout] test cli::unit_tests::parse_ignored ... ok
[INFO] [stdout] test cli::unit_tests::parse_include_ignored ... ok
[INFO] [stdout] test cli::unit_tests::parse_invalid_output ... ok
[INFO] [stdout] test cli::unit_tests::parse_invalid_sort ... ok
[INFO] [stdout] test bencher::unit_tests::second_run_panics - should panic ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_counter_uses_constant_value ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_calls_generator_per_iteration ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_refs ... ok
[INFO] [stdout] test cli::unit_tests::parse_invalid_threshold ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_propagates_panic - should panic ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_provides_fresh_input ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_non_divisible_sample_count ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_collects_samples ... ok
[INFO] [stdout] test cli::unit_tests::parse_list ... ok
[INFO] [stdout] test cli::unit_tests::parse_list_terse ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_default_none ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_microseconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_milliseconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_missing_unit ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_nanoseconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_seconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_no_number ... ok
[INFO] [stdout] test cli::unit_tests::parse_output_json ... ok
[INFO] [stdout] test cli::unit_tests::parse_skip_overrides_filter ... ok
[INFO] [stdout] test cli::unit_tests::parse_sort ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_multiple ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_no_flag ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_single ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_zero ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_zero_mixed ... ok
[INFO] [stdout] test cli::unit_tests::parse_unknown_flag ... ok
[INFO] [stdout] test cli::unit_tests::parse_threshold ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_threaded_collects_samples ... ok
[INFO] [stdout] test config::unit_tests::defaults_all_none ... ok
[INFO] [stdout] test config::unit_tests::overwrite_all_fields_child_wins ... ok
[INFO] [stdout] test cli::unit_tests::parse_test ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_invalid ... ok
[INFO] [stdout] test config::unit_tests::overwrite_inheritance ... ok
[INFO] [stdout] test config::unit_tests::overwrite_threads_inherits_from_parent ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_threaded_generator_call_count ... ok
[INFO] [stdout] test config::unit_tests::overwrite_precedence ... ok
[INFO] [stdout] test config::unit_tests::resolve_defaults ... ok
[INFO] [stdout] test config::unit_tests::overwrite_threads_child_wins ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_sorted_and_deduped ... ok
[INFO] [stdout] test counter::collection::unit_tests::counts_returns_raw_values ... ok
[INFO] [stdout] test config::unit_tests::resolve_partial ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_empty ... ok
[INFO] [stdout] test cli::unit_tests::parse_save_baseline ... ok
[INFO] [stdout] test cli::unit_tests::parse_skip ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_zero_mixed_with_explicit ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_multiple ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_large_values_no_overflow ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_explicit_list ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_default_is_single ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_single ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_non_integer ... ok
[INFO] [stdout] test counter::collection::unit_tests::with_capacity_starts_empty ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_zero_expands_to_available ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_slice_u8 ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_str ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_four_threads ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_zero ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_str_multibyte ... ok
[INFO] [stdout] test counter::collection::unit_tests::new_is_empty ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_new ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_slice_u32 ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_slice_zst ... ok
[INFO] [stdout] test counter::collection::unit_tests::push_increments_len ... ok
[INFO] [stdout] test counter::unit_tests::counter_trait_dynamic_dispatch ... ok
[INFO] [stdout] test counter::unit_tests::cycles_count_new ... ok
[INFO] [stdout] test counter::unit_tests::items_count_new ... ok
[INFO] [stdout] test counter::unit_tests::items_count_of_empty_iter ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_of_str_ascii ... ok
[INFO] [stdout] test counter::unit_tests::items_count_of_iter ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_of_str_emoji ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_of_str_unicode ... ok
[INFO] [stdout] test entry::tree::unit_tests::entry_tree_group_options ... ok
[INFO] [stdout] test entry::tree::unit_tests::entry_tree_grouping ... ok
[INFO] [stdout] test entry::tree::unit_tests::entry_tree_retain ... ok
[INFO] [stdout] test entry::tree::unit_tests::sort_by_stats_missing_entries_sort_last ... ok
[INFO] [stdout] test entry::tree::unit_tests::sort_by_stats_p50 ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_empty_results ... ok
[INFO] [stdout] test counter::unit_tests::counter_kind_eq ... ok
[INFO] [stdout] test counter::unit_tests::items_count_of_iter_consumes ... ok
[INFO] [stdout] test entry::list::unit_tests::entry_list_push_and_iter ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_refs_threaded ... ok
[INFO] [stdout] test entry::tree::unit_tests::natural_cmp_ordering ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_output_header_and_row ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_bytes_decimal ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_infinity_passthrough ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_neg_infinity_passthrough ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_nan_passthrough ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_threads_column_header_present ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_negative ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_threads_column_values ... ok
[INFO] [stdout] test entry::tree::unit_tests::sort_by_stats_name_delegates_to_sort ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_negative_sub_one ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_preserves_significant_digits ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_multiple_rows ... ok
[INFO] [stdout] test bencher::unit_tests::counter_with_adaptive_tuning ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_sub_one ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_sub_one_with_leading_zeros ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_sub_one_two_sig_figs ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_trims_trailing_zeros ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_truncates_not_rounds ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_format_throughput ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_binary_thresholds ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_bytes_binary ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_format_bytes_format ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_invalid_unit ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_width_padding ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_ranges ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_zero_count ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_zero ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_bytes ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_bytes_binary_exact ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_bytes_decimal_exact ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_cycles ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_items ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_negative ... ok
[INFO] [stdout] test output::table::unit_tests::nested_group_tree_structure ... ok
[INFO] [stdout] test stats::exact::unit_tests::compute_all_percentiles ... ok
[INFO] [stdout] test stats::exact::unit_tests::mean_basic ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_zero_picos ... ok
[INFO] [stdout] test output::table::unit_tests::write_leaf_with_throughput ... ok
[INFO] [stdout] test output::table::unit_tests::ignored_leaf_placeholder ... ok
[INFO] [stdout] test output::table::unit_tests::prefix_restores_after_nested_groups ... ok
[INFO] [stdout] test output::table::unit_tests::table_renders_header ... ok
[INFO] [stdout] test output::table::unit_tests::table_renders_row ... ok
[INFO] [stdout] test output::table::unit_tests::table_tree_structure ... ok
[INFO] [stdout] test output::table::unit_tests::write_comparison_row_no_panic ... ok
[INFO] [stdout] test stats::exact::unit_tests::mean_empty_panics - should panic ... ok
[INFO] [stdout] test stats::exact::unit_tests::mean_single ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_ten_samples ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_two_samples ... ok
[INFO] [stdout] test stats::exact::unit_tests::std_dev_uniform ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_out_of_range_panics - should panic ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_single_sample ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_add_and_sort ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_new_is_empty ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_empty ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_total_duration ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_with_capacity ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_single_value ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_empty_panics - should panic ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_mean_and_stddev ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_hundred_samples ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_exact_mode_unsorted_input ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_min_max_len ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_empty_panics - should panic ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_exact_mode ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_exact_mode_with_sample_size ... ok
[INFO] [stdout] test stats::exact::unit_tests::std_dev_identical_values ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_raw_picos_preserves_order ... ok
[INFO] [stdout] test stats::exact::unit_tests::std_dev_empty_panics - should panic ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_all_ok ... ok
[INFO] [stdout] test stats::unit_tests::exact_percentiles_match_uniform_analytical ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_config_too_low ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_basic_percentiles ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_total_iterations ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_max_time_truncated ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_very_low ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_clamps_u128_to_u64 ... ok
[INFO] [stdout] test thread::unit_tests::atomic_flag_default_true ... ok
[INFO] [stdout] test thread::unit_tests::atomic_flag_roundtrip ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_zero_sample_size_panics - should panic ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_deref_mut ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_size ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_swallows_main_thread_panic ... ok
[INFO] [stdout] test thread::unit_tests::atomic_flag_default_false ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_alignment ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_swallows_aux_thread_panic ... ok
[INFO] [stdout] test stats::unit_tests::hdr_wrapper_matches_raw_hdrhistogram ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_panic_leaves_none_slot ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_zero_aux ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_one_aux ... ok
[INFO] [stdout] test thread::unit_tests::sync_wrap_deref_mut ... ok
[INFO] [stdout] test thread::unit_tests::sync_wrap_is_sync ... ok
[INFO] [stdout] test thread::unit_tests::sync_wrap_deref ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::add_assign ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_add ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_div_u64 ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_mul_u64 ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_sub ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::checked_sub_none ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::checked_sub_some ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_microseconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_milliseconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_nanoseconds ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_thread_id_zero_is_main ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_new ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_picoseconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_seconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::from_duration_micros ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::from_duration_millis ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::ordering ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::zero ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::from_duration_nanos ... ok
[INFO] [stdout] test stats::unit_tests::cross_validate_exact_vs_hdr ... ok
[INFO] [stdout] test util::sort::unit_tests::natural_sort_mixed ... ok
[INFO] [stdout] test util::sort::unit_tests::natural_sort_order ... ok
[INFO] [stdout] test time::unit_tests::timer_overhead_nonnegative ... ok
[INFO] [stdout] test time::unit_tests::timer_precision_nonzero ... ok
[INFO] [stdout] test time::unit_tests::measure_precision_nonzero ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_hdr_mode ... ok
[INFO] [stdout] test time::unit_tests::timer_best_available_works ... ok
[INFO] [stdout] test time::unit_tests::instant_timer_measures_time ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_scaling ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_hdr_boundary ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_nan ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_whole_number ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_refs_threaded_counter_matches ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_deref ... ok
[INFO] [stdout] test thread::unit_tests::pool_spawns_lazily ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_with_sleep ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_four_aux ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 241 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/compile_fail.rs (/opt/rustwide/target/debug/deps/compile_fail-0b1d659a53a5f703)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]     Checking cfg-if v1.0.4
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]     Checking simd-adler32 v0.3.8
[INFO] [stderr]     Checking adler2 v2.0.1
[INFO] [stderr]     Checking memchr v2.8.0
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]     Checking minimal-lexical v0.2.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]     Checking base64 v0.21.7
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]     Checking scopeguard v1.2.0
[INFO] [stderr]     Checking bitflags v2.11.0
[INFO] [stderr]     Checking byteorder v1.5.0
[INFO] [stderr]     Checking once_cell v1.21.3
[INFO] [stderr]     Checking miniz_oxide v0.8.9
[INFO] [stderr]     Checking linux-raw-sys v0.12.1
[INFO] [stderr]     Checking smallvec v1.15.1
[INFO] [stderr]     Checking lock_api v0.4.14
[INFO] [stderr]     Checking rustc-hash v2.1.1
[INFO] [stderr]     Checking hashbrown v0.14.5
[INFO] [stderr]     Checking nom v7.1.3
[INFO] [stderr]     Checking arrayvec v0.7.6
[INFO] [stderr]     Checking saa v5.5.0
[INFO] [stderr]     Checking sdd v4.6.2
[INFO] [stderr]     Checking static_assertions v1.1.0
[INFO] [stderr]     Checking fastrand v2.3.0
[INFO] [stderr]     Checking flate2 v1.1.9
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]     Checking scc v3.6.5
[INFO] [stderr]     Checking crossbeam-channel v0.5.15
[INFO] [stderr]     Checking crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling libmimalloc-sys v0.1.44
[INFO] [stderr]     Checking hdrhistogram v7.5.4
[INFO] [stderr]     Checking seize v0.5.1
[INFO] [stderr]     Checking dashmap v6.1.0
[INFO] [stderr]     Checking tempfile v3.26.0
[INFO] [stderr]     Checking mimalloc v0.1.48
[INFO] [stderr]     Checking masstree v0.8.1
[INFO] [stderr]    Compiling pbench-macros v0.2.0 (/opt/rustwide/workdir/pbench-macros)
[INFO] [stderr]     Checking pbench v0.2.0 (/opt/rustwide/workdir/pbench)
[INFO] [stderr] warning: unnecessary `unsafe` block
[INFO] [stderr]   --> /opt/rustwide/workdir/pbench/src/time/tsc.rs:97:9
[INFO] [stderr]    |
[INFO] [stderr] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stderr]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `pbench` (lib) generated 1 warning
[INFO] [stderr]     Checking pbench-tests v0.0.0 (/opt/rustwide/target/tests/trybuild/pbench)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.04s
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stdout] test compile_fail ... ok
[INFO] [stderr] test tests/compile_fail/duplicate_option.rs ... ok
[INFO] [stdout] 
[INFO] [stderr] test tests/compile_fail/invalid_args.rs ... ok
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 23.06s
[INFO] [stderr] test tests/compile_fail/unknown_option.rs ... ok
[INFO] [stdout] 
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-e95cd6e944408a02)
[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 unittests src/lib.rs (/opt/rustwide/target/debug/deps/pbench_macros-57bde9ecd2d11056)
[INFO] [stderr]    Doc-tests pbench
[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] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test pbench/src/bencher/mod.rs - bencher::Bencher<'ctx>::counter (line 172) ... ignored
[INFO] [stdout] test pbench/src/counter/mod.rs - counter::BytesCount (line 66) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 18) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 47) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 59) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 70) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 82) ... ignored
[INFO] [stdout] test pbench/src/config.rs - config::BenchOptions (line 20) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.45s; merged doctests compilation took 0.44s
[INFO] [stderr]    Doc-tests pbench_macros
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test pbench-macros/src/lib.rs - bench (line 98) ... ignored
[INFO] [stdout] test pbench-macros/src/lib.rs - bench_group (line 263) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.21s; merged doctests compilation took 0.20s
[INFO] running `Command { std: "docker" "inspect" "627f28b01f0ceeaa64debd3ea625da0669749743cce0aeb1b878488f89ff0cd6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "627f28b01f0ceeaa64debd3ea625da0669749743cce0aeb1b878488f89ff0cd6", kill_on_drop: false }`
[INFO] [stdout] 627f28b01f0ceeaa64debd3ea625da0669749743cce0aeb1b878488f89ff0cd6