[INFO] fetching crate redoubt-hkdf 0.1.0-rc.18...
[INFO] testing redoubt-hkdf-0.1.0-rc.18 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate redoubt-hkdf 0.1.0-rc.18 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate redoubt-hkdf 0.1.0-rc.18
[INFO] finished tweaking crates.io crate redoubt-hkdf 0.1.0-rc.18
[INFO] tweaked toml for crates.io crate redoubt-hkdf 0.1.0-rc.18 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate redoubt-hkdf 0.1.0-rc.18 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 redoubt-hkdf 0.1.0-rc.18 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-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185
[INFO] running `Command { std: "docker" "start" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", 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" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185" "/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" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", 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" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling find-msvc-tools v0.1.6
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling redoubt-asm v0.1.0-rc.18
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling cc v1.2.51
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling indexmap v2.12.1
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling redoubt-mem-core v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-hkdf v0.1.0-rc.18 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling redoubt-mem v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-util v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-zero-core v0.1.0-rc.18
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling redoubt-zero-derive v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-zero v0.1.0-rc.18
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 15.08s
[INFO] running `Command { std: "docker" "inspect" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", 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" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185" "/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 libc v0.2.178
[INFO] [stderr]    Compiling zerocopy v0.8.31
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling rstest_macros v0.27.0
[INFO] [stderr]    Compiling redoubt-forensics-core v0.1.0-rc.18
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling quiesce v0.0.1
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling redoubt-forensics-allocator v0.1.0-rc.18
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.9.0
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling redoubt-zero-derive v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-forensics-macros v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-forensics v0.1.0-rc.18
[INFO] [stderr]    Compiling redoubt-zero v0.1.0-rc.18
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling redoubt-hkdf v0.1.0-rc.18 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling rstest v0.27.0
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 55.47s
[INFO] running `Command { std: "docker" "inspect" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", 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" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185" "/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.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/redoubt-hkdf/811d47d857fc0e36/out/redoubt_hkdf-811d47d857fc0e36)
[INFO] [stdout] 
[INFO] [stdout] running 184 tests
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_r8_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_rax_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_rsi_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm0_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm0_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_rdx_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm10_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_r11_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm11_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm11_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm10_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm12_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_r9_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_rdi_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm12_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm13_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm13_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm14_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm14_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm15_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_r10_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm15_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm1_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm1_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_rcx_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm2_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm4_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm2_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm5_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm3_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm3_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm4_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm6_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm5_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm6_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm7_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_byte_left_anywhere_in_the_hash_frame_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_byte_left_anywhere_in_the_hkdf_frame_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm8_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm8_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm7_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm9_low_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_byte_left_anywhere_in_the_absorb_frame_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::every_register::test_xmm9_high_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_byte_left_anywhere_in_the_hmac_frame_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_byte_left_anywhere_in_the_compress_block_frame_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_byte_left_anywhere_in_the_update_finalize_frame_is_seen ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_absorb_leaves_the_residue_its_case_declares::case_6_real ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_compress_block_frame_written_and_emptied_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_absorb_leaves_the_residue_its_case_declares::case_3_high_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_an_absorb_frame_written_and_emptied_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_absorb_leaves_the_residue_its_case_declares::case_5_nothing_ran ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_an_empty_register_file_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_an_hmac_frame_written_and_emptied_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_absorb_leaves_the_residue_its_case_declares::case_1_registers_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_an_update_finalize_frame_written_and_emptied_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_absorb_leaves_the_residue_its_case_declares::case_4_frame_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_compress_block_leaves_the_residue_its_case_declares::case_2_low_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_an_hkdf_frame_written_and_emptied_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_compress_block_leaves_the_residue_its_case_declares::case_1_registers_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_absorb_leaves_the_residue_its_case_declares::case_2_low_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_a_hash_frame_written_and_emptied_reads_as_empty ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_compress_block_leaves_the_residue_its_case_declares::case_5_nothing_ran ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_frame_hkdf_clears_every_byte_except_the_requested_one ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_compress_block_leaves_the_residue_its_case_declares::case_3_high_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_compress_block_leaves_the_residue_its_case_declares::case_4_frame_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_frame_compress_block_clears_every_byte_except_the_requested_one ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_frame_hmac_clears_every_byte_except_the_requested_one ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_frame_update_finalize_clears_every_byte_except_the_requested_one ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_vector_high_empties_every_general_register_in_the_budget ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_vector_high_fills_only_the_high_half_of_every_vector_register ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_compress_block_leaves_the_residue_its_case_declares::case_6_real ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_frame_absorb_clears_every_byte_except_the_requested_one ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_vector_low_empties_every_general_register_in_the_budget ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_frame_hash_clears_every_byte_except_the_requested_one ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_vector_low_fills_only_the_low_half_of_every_vector_register ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hash_leaves_the_residue_its_case_declares::case_1_registers_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hash_leaves_the_residue_its_case_declares::case_6_real ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hkdf_leaves_the_residue_its_case_declares::case_1_registers_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_registers_fills_every_general_register_in_the_budget ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hkdf_leaves_the_residue_its_case_declares::case_2_low_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_dirty_registers_fills_every_vector_register_in_the_budget ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hkdf_leaves_the_residue_its_case_declares::case_4_frame_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hash_leaves_the_residue_its_case_declares::case_5_nothing_ran ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hash_leaves_the_residue_its_case_declares::case_2_low_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hmac_leaves_the_residue_its_case_declares::case_1_registers_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hash_leaves_the_residue_its_case_declares::case_3_high_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hash_leaves_the_residue_its_case_declares::case_4_frame_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hmac_leaves_the_residue_its_case_declares::case_2_low_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hmac_leaves_the_residue_its_case_declares::case_3_high_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hmac_leaves_the_residue_its_case_declares::case_4_frame_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_no_offset_expects_an_empty_window ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hkdf_leaves_the_residue_its_case_declares::case_3_high_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hmac_leaves_the_residue_its_case_declares::case_5_nothing_ran ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hkdf_leaves_the_residue_its_case_declares::case_5_nothing_ran ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hmac_leaves_the_residue_its_case_declares::case_6_real ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_lists_name_as_many_registers_as_there_are_tests ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_hkdf_leaves_the_residue_its_case_declares::case_6_real ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_measurement_of_hash_reads_the_window_the_writer_filled ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_measurement_of_absorb_reads_the_window_the_writer_filled ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_measurement_of_compress_block_reads_the_window_the_writer_filled ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_measurement_of_update_finalize_reads_the_window_the_writer_filled ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_update_finalize_leaves_the_residue_its_case_declares::case_2_low_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_measurement_of_hkdf_reads_the_window_the_writer_filled ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_update_finalize_leaves_the_residue_its_case_declares::case_3_high_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_the_measurement_of_hmac_reads_the_window_the_writer_filled ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_update_finalize_leaves_the_residue_its_case_declares::case_1_registers_left_full ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_update_finalize_leaves_the_residue_its_case_declares::case_5_nothing_ran ... ok
[INFO] [stdout] test tests::backend::rust::sha256::test_sha256_state_is_zeroizable ... ok
[INFO] [stdout] test tests::backend::rust::sha256::test_sha256_state_zeroizes_on_drop ... ok
[INFO] [stdout] test tests::backend::rust::sha256::test_update_answers_the_same_for_a_message_cut_at_every_alignment ... ok
[INFO] [stdout] test tests::backend::rust::sha256::test_update_answers_the_same_for_a_message_cut_inside_a_block ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_update_finalize_leaves_the_residue_its_case_declares::case_4_frame_left_full ... ok
[INFO] [stdout] test tests::backend::rust::hmac::test_hmac_sha256_state_is_zeroizable ... ok
[INFO] [stdout] test tests::backend::rust::hkdf::test_hkdf_sha256_state_is_zeroizable ... ok
[INFO] [stdout] test tests::backend::asm::probes::test_update_finalize_leaves_the_residue_its_case_declares::case_6_real ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_set_bsig1_answers_what_the_standard_defines ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_set_ssig0_answers_what_the_standard_defines ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_set_ch_answers_what_the_standard_defines ... ok
[INFO] [stdout] test tests::backend::rust::hkdf::test_hkdf_sha256_state_zeroizes_on_drop ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_set_maj_answers_what_the_standard_defines ... ok
[INFO] [stdout] test tests::backend::rust::hmac::test_hmac_sha256_state_zeroizes_on_drop ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_set_ssig1_answers_what_the_standard_defines ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_set_bsig0_answers_what_the_standard_defines ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_u32_from_be_empties_the_bytes ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_u32_from_be_answers_as_from_be_bytes ... ok
[INFO] [stdout] test tests::backend::seam::sha256_compress_block::test_compress_block_answers_the_published_digest_for_every_short_message::case_1_rust ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_u32_to_be_answers_as_to_be_bytes ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_u32_to_be_empties_the_word ... ok
[INFO] [stdout] test tests::backend::rust::word32::test_word32_is_zeroizable ... ok
[INFO] [stdout] test tests::backend::seam::sha256_compress_block::test_compress_block_answers_the_published_digest_for_every_short_message::case_2_auto ... ok
[INFO] [stdout] test tests::backend::seam::sha256_compress_block::test_compress_block_answers_the_published_digest_for_every_long_message::case_2_auto ... ok
[INFO] [stdout] test tests::backend::seam::sha256_hash::test_hash_answers_the_published_digest_for_every_short_message::case_1_rust ... ok
[INFO] [stdout] test tests::backend::seam::sha256_hash::test_hash_answers_the_published_digest_for_every_short_message::case_2_auto ... ok
[INFO] [stdout] test tests::backend::seam::sha256_hash::test_hash_answers_the_published_digest_for_every_long_message::case_2_auto ... ok
[INFO] [stdout] test tests::backend::test_this_target_has_the_assembly ... ok
[INFO] [stdout] test tests::backend::seam::sha256_compress_block::test_compress_block_answers_the_published_digest_for_every_long_message::case_1_rust ... ok
[INFO] [stdout] test tests::backend::seam::sha256_hash::test_hash_answers_the_published_digest_for_every_long_message::case_1_rust ... ok
[INFO] [stdout] test tests::forensics::hkdf::test_making_a_derivation_leaves_nothing ... ignored, Reads no secret: it names a backend.
[INFO] [stdout] test tests::backend::seam::sha256_hash::test_hash_answers_the_published_checkpoints_of_the_monte_carlo::case_2_auto ... ok
[INFO] [stdout] test tests::forensics::hkdf::test_what_deriving_1024_bytes_wrote_is_found_while_the_output_holds_it ... ok
[INFO] [stdout] test tests::backend::seam::sha256_hash::test_hash_answers_the_published_checkpoints_of_the_monte_carlo::case_1_rust ... ok
[INFO] [stdout] test tests::hkdf::test_hkdf_fills_output_of_exactly_the_last_block ... ok
[INFO] [stdout] test tests::hkdf::test_hkdf_reports_output_too_long_one_byte_past_the_last_block ... ok
[INFO] [stdout] test tests::hkdf::test_hkdf_reports_output_too_long_where_the_struct_reports_it ... ok
[INFO] [stdout] test tests::hkdf::test_hkdf_returns_ok_for_no_output_at_all ... ok
[INFO] [stdout] test tests::hkdf::test_hkdf_writes_what_the_struct_derives ... ok
[INFO] [stdout] test tests::libsodium::test_a_length_does_not_prefix_a_longer_one ... ok
[INFO] [stdout] test tests::forensics::hkdf::test_deriving_nothing_leaves_nothing ... ok
[INFO] [stdout] test tests::forensics::hkdf::test_deriving_1024_bytes_leaves_nothing ... FAILED
[INFO] [stdout] test tests::libsodium::test_a_row_with_one_okm_bit_turned_over_disagrees_with_libsodium ... ok
[INFO] [stdout] test tests::forensics::hkdf::test_deriving_more_than_the_counter_has_blocks_for_leaves_nothing ... FAILED
[INFO] [stdout] test tests::forensics::hkdf::test_deriving_8160_bytes_leaves_nothing ... FAILED
[INFO] [stdout] test tests::forensics::hkdf::test_hkdf_leaves_nothing ... FAILED
[INFO] [stdout] test tests::forensics::hkdf::test_what_deriving_8160_bytes_wrote_is_found_while_the_output_holds_it ... ok
[INFO] [stdout] test tests::forensics::hkdf::test_what_hkdf_wrote_is_found_while_the_output_holds_it ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_2_1 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_1_0 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_3_2 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_4_3 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_5_4 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_6_5 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_8_7 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_1_rust::shard_7_6 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_4_3 ... ok
[INFO] [stdout] test tests::libsodium::test_the_file_covers_the_lengths_the_walk_reaches ... ok
[INFO] [stdout] test tests::libsodium::test_the_material_differs_between_fields ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_6_5 ... ok
[INFO] [stdout] test tests::rfc::test_hkdf_answers_every_output_rfc_5869_publishes::case_1_rust ... ok
[INFO] [stdout] test tests::rfc::test_hkdf_answers_every_output_rfc_5869_publishes::case_2_auto ... ok
[INFO] [stdout] test tests::libsodium::test_the_seeds_are_the_ones_the_file_was_generated_from ... ok
[INFO] [stdout] test tests::rfc::test_hmac_answers_every_tag_rfc_4231_publishes::case_2_auto ... ok
[INFO] [stdout] test tests::sha256::test_sha256_returns_every_short_message_digest ... ok
[INFO] [stdout] test tests::rfc::test_hmac_answers_every_tag_rfc_4231_publishes::case_1_rust ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_1_0 ... ok
[INFO] [stdout] test tests::wycheproof::test_hmac_answers_every_tag_wycheproof_publishes::case_1_rust ... ok
[INFO] [stdout] test tests::wycheproof::test_derive_answers_every_output_wycheproof_publishes::case_2_auto ... ok
[INFO] [stdout] test tests::wycheproof::test_derive_answers_every_output_wycheproof_publishes::case_1_rust ... ok
[INFO] [stdout] test tests::wycheproof::test_hmac_answers_every_tag_wycheproof_publishes::case_2_auto ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_2_1 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_3_2 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_5_4 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_7_6 ... ok
[INFO] [stdout] test tests::libsodium::test_every_length_agrees_with_libsodium::case_2_auto::shard_8_7 ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::forensics::hkdf::test_deriving_1024_bytes_leaves_nothing stdout ----
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     2  runs         117872  swept       1939296  whole no
[INFO] [stdout]   deriving 1024 bytes, of the key material score            0  widest     3  runs         676611  swept      12518944  whole no
[INFO] [stdout]                                score            +0  widest     +1  runs         +558739  swept      +10579648
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     3  runs        1903956  swept       1939296  whole no
[INFO] [stdout]   deriving 1024 bytes, of the output score         6084  widest  1024  runs       12292604  swept      12518944  whole yes
[INFO] [stdout]                                score         +6084  widest  +1021  runs       +10388648  swept      +10579648  and the whole secret surfaced
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::forensics::hkdf::test_deriving_1024_bytes_leaves_nothing' (1270) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/redoubt-forensics-core-0.1.0-rc.18/src/verdicts.rs:55:5:
[INFO] [stdout] the whole secret was left behind by deriving 1024 bytes, of the output: score         6084  widest  1024  runs       12292604  swept      12518944  whole yes
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64048db061d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x64048db1ba1a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x64048db0a84c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x64048dae1196 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x64048dafe599 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x64048da80770 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x64048dafe752 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x64048dae1242 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x64048dad9e09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x64048dae1d2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x64048db1c17c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x64048dac9ec8 - redoubt_forensics_core[32e8484f8a33e1c3]::verdicts::leaves_nothing
[INFO] [stdout]   12:     0x64048dac8c6b - <redoubt_forensics_core[32e8484f8a33e1c3]::watching::Watching>::none_left
[INFO] [stdout]   13:     0x64048da5e2af - <redoubt_hkdf[7cee919403ea840c]::tests::forensics::hkdf::test_deriving_1024_bytes_leaves_nothing::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x64048da73bbb - 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]   15:     0x64048da80d95 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x64048da7a684 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x64048da83e22 - <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]   18:     0x64048db05a2f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x789ce3039dfa - <unknown>
[INFO] [stdout]   20:     0x789ce30cd3d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::forensics::hkdf::test_deriving_more_than_the_counter_has_blocks_for_leaves_nothing stdout ----
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     2  runs         129131  swept       2102032  whole no
[INFO] [stdout]   deriving more than the counter has blocks for score          223  widest    32  runs        1032569  swept      22636064  whole yes
[INFO] [stdout]                                score          +223  widest    +30  runs         +903438  swept      +20534032  and the whole secret surfaced
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::forensics::hkdf::test_deriving_more_than_the_counter_has_blocks_for_leaves_nothing' (1276) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/redoubt-forensics-core-0.1.0-rc.18/src/verdicts.rs:55:5:
[INFO] [stdout] the whole secret was left behind by deriving more than the counter has blocks for: score          223  widest    32  runs        1032569  swept      22636064  whole yes
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64048db061d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x64048db1ba1a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x64048db0a84c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x64048dae1196 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x64048dafe599 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x64048da80770 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x64048dafe752 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x64048dae1242 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x64048dad9e09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x64048dae1d2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x64048db1c17c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x64048dac9ec8 - redoubt_forensics_core[32e8484f8a33e1c3]::verdicts::leaves_nothing
[INFO] [stdout]   12:     0x64048da60196 - <redoubt_hkdf[7cee919403ea840c]::tests::forensics::hkdf::test_deriving_more_than_the_counter_has_blocks_for_leaves_nothing::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x64048da73bbb - 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]   14:     0x64048da80d95 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x64048da7a684 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x64048da83e22 - <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]   17:     0x64048db05a2f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x789ce3039dfa - <unknown>
[INFO] [stdout]   19:     0x789ce30cd3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::forensics::hkdf::test_deriving_8160_bytes_leaves_nothing stdout ----
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     2  runs          73654  swept       1735600  whole no
[INFO] [stdout]   deriving 8160 bytes, of the key material score            0  widest     3  runs        1064009  swept      22955552  whole no
[INFO] [stdout]                                score            +0  widest     +1  runs         +990355  swept      +21219952
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     3  runs        1714680  swept       1735600  whole no
[INFO] [stdout]   deriving 8160 bytes, of the output score         6083  widest  1024  runs       22593958  swept      22955552  whole yes
[INFO] [stdout]                                score         +6083  widest  +1021  runs       +20879278  swept      +21219952  and the whole secret surfaced
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::forensics::hkdf::test_deriving_8160_bytes_leaves_nothing' (1271) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/redoubt-forensics-core-0.1.0-rc.18/src/verdicts.rs:55:5:
[INFO] [stdout] the whole secret was left behind by deriving 8160 bytes, of the output: score         6083  widest  1024  runs       22593958  swept      22955552  whole yes
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64048db061d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x64048db1ba1a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x64048db0a84c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x64048dae1196 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x64048dafe599 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x64048da80770 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x64048dafe752 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x64048dae1242 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x64048dad9e09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x64048dae1d2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x64048db1c17c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x64048dac9ec8 - redoubt_forensics_core[32e8484f8a33e1c3]::verdicts::leaves_nothing
[INFO] [stdout]   12:     0x64048dac8c6b - <redoubt_forensics_core[32e8484f8a33e1c3]::watching::Watching>::none_left
[INFO] [stdout]   13:     0x64048da5edbf - <redoubt_hkdf[7cee919403ea840c]::tests::forensics::hkdf::test_deriving_8160_bytes_leaves_nothing::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x64048da73bbb - 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]   15:     0x64048da80d95 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x64048da7a684 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x64048da83e22 - <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]   18:     0x64048db05a2f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x789ce3039dfa - <unknown>
[INFO] [stdout]   20:     0x789ce30cd3d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::forensics::hkdf::test_hkdf_leaves_nothing stdout ----
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     2  runs         202583  swept       2973376  whole no
[INFO] [stdout]   hkdf, of the key material    score            0  widest     3  runs        1213896  swept      26666528  whole no
[INFO] [stdout]                                score            +0  widest     +1  runs        +1011313  swept      +23693152
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout]   nothing held yet             score            0  widest     3  runs        2911929  swept       2973376  whole no
[INFO] [stdout]   hkdf, of the output          score         6083  widest  1024  runs       26253243  swept      26666528  whole yes
[INFO] [stdout]                                score         +6083  widest  +1021  runs       +23341314  swept      +23693152  and the whole secret surfaced
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::forensics::hkdf::test_hkdf_leaves_nothing' (1279) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/redoubt-forensics-core-0.1.0-rc.18/src/verdicts.rs:55:5:
[INFO] [stdout] the whole secret was left behind by hkdf, of the output: score         6083  widest  1024  runs       26253243  swept      26666528  whole yes
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64048db061d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x64048db1ba1a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x64048db0a84c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x64048dae1196 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x64048dafe599 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x64048da80770 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x64048dafe752 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x64048dae1242 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x64048dad9e09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x64048dae1d2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x64048db1c17c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x64048dac9ec8 - redoubt_forensics_core[32e8484f8a33e1c3]::verdicts::leaves_nothing
[INFO] [stdout]   12:     0x64048dac8c6b - <redoubt_forensics_core[32e8484f8a33e1c3]::watching::Watching>::none_left
[INFO] [stdout]   13:     0x64048da5ced1 - <redoubt_hkdf[7cee919403ea840c]::tests::forensics::hkdf::test_hkdf_leaves_nothing::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x64048da73bbb - 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]   15:     0x64048da80d95 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x64048da7a684 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x64048da83e22 - <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]   18:     0x64048db05a2f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x789ce3039dfa - <unknown>
[INFO] [stdout]   20:     0x789ce30cd3d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::forensics::hkdf::test_deriving_1024_bytes_leaves_nothing
[INFO] [stdout]     tests::forensics::hkdf::test_deriving_8160_bytes_leaves_nothing
[INFO] [stdout]     tests::forensics::hkdf::test_deriving_more_than_the_counter_has_blocks_for_leaves_nothing
[INFO] [stdout]     tests::forensics::hkdf::test_hkdf_leaves_nothing
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 179 passed; 4 failed; 1 ignored; 0 measured; 0 filtered out; finished in 21.91s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185", kill_on_drop: false }`
[INFO] [stdout] c113e4c0d642b8f7e59d13080f056fe47dfc1aec7e87f54c9e19c0c3ccdba185
