[INFO] fetching crate dupblaster 0.3.0...
[INFO] testing dupblaster-0.3.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate dupblaster 0.3.0 into /workspace/builds/worker-5-tc1/source
[INFO] removed /workspace/builds/worker-5-tc1/source/.cargo/config.toml
[INFO] removed /workspace/builds/worker-5-tc1/source/rust-toolchain.toml
[INFO] started tweaking crates.io crate dupblaster 0.3.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate dupblaster 0.3.0
[INFO] tweaked toml for crates.io crate dupblaster 0.3.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate dupblaster 0.3.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate dupblaster 0.3.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wide v1.4.0
[INFO] [stderr]   Downloaded noodles-core v0.20.0
[INFO] [stderr]   Downloaded xmlwriter v0.1.0
[INFO] [stderr]   Downloaded unicode-vo v0.1.0
[INFO] [stderr]   Downloaded ringbuf v0.5.0
[INFO] [stderr]   Downloaded unicode-bidi-mirroring v0.4.0
[INFO] [stderr]   Downloaded libdeflater v1.25.2
[INFO] [stderr]   Downloaded unicode-ccc v0.4.0
[INFO] [stderr]   Downloaded pico-args v0.5.0
[INFO] [stderr]   Downloaded simplecss v0.2.2
[INFO] [stderr]   Downloaded noodles-csi v0.56.0
[INFO] [stderr]   Downloaded noodles-bgzf v0.47.0
[INFO] [stderr]   Downloaded imagesize v0.13.0
[INFO] [stderr]   Downloaded anyhow v1.0.103
[INFO] [stderr]   Downloaded rawb-io v0.1.0
[INFO] [stderr]   Downloaded noodles-util v0.80.0
[INFO] [stderr]   Downloaded data-url v0.3.2
[INFO] [stderr]   Downloaded noodles-fasta v0.62.0
[INFO] [stderr]   Downloaded libbz2-rs-sys v0.2.5
[INFO] [stderr]   Downloaded svg2pdf v0.13.0
[INFO] [stderr]   Downloaded svgtypes v0.15.3
[INFO] [stderr]   Downloaded subsetter v0.2.6
[INFO] [stderr]   Downloaded polycool v0.4.0
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.123
[INFO] [stderr]   Downloaded noodles-bam v0.90.0
[INFO] [stderr]   Downloaded safe_arch v1.0.0
[INFO] [stderr]   Downloaded gif v0.13.3
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.123
[INFO] [stderr]   Downloaded colorous v1.0.16
[INFO] [stderr]   Downloaded fgoxide v0.6.0
[INFO] [stderr]   Downloaded serde-aux v4.7.0
[INFO] [stderr]   Downloaded noodles-sam v0.85.0
[INFO] [stderr]   Downloaded resvg v0.45.1
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.123
[INFO] [stderr]   Downloaded noodles-cram v0.94.0
[INFO] [stderr]   Downloaded usvg v0.45.1
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.123
[INFO] [stderr]   Downloaded kurbo v0.11.3
[INFO] [stderr]   Downloaded js-sys v0.3.100
[INFO] [stderr]   Downloaded fgumi-raw-bam v0.2.0
[INFO] [stderr]   Downloaded libdeflate-sys v1.25.2
[INFO] [stderr]   Downloaded kurbo v0.13.1
[INFO] [stderr]   Downloaded lzma-rust2 v0.16.4
[INFO] [stderr]   Downloaded rustybuzz v0.20.1
[INFO] [stderr]   Downloaded write-fonts v0.48.1
[INFO] [stderr]   Downloaded skrifa v0.42.1
[INFO] [stderr]   Downloaded read-fonts v0.39.2
[INFO] [stderr]   Downloaded bgzf v0.5.0
[INFO] [stderr]   Downloaded pdf-writer v0.12.1
[INFO] [stderr]   Downloaded kuva v0.4.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100
[INFO] running `Command { std: "docker" "start" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", 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" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", 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" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling jobserver v0.1.35
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling slotmap v1.1.1
[INFO] [stderr]    Compiling core_maths v0.1.1
[INFO] [stderr]    Compiling fdeflate v0.3.7
[INFO] [stderr]    Compiling float-cmp v0.9.0
[INFO] [stderr]    Compiling strict-num v0.1.1
[INFO] [stderr]    Compiling ttf-parser v0.25.1
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling cc v1.2.62
[INFO] [stderr]    Compiling roxmltree v0.20.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling lexical-util v1.0.7
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling tinyvec v1.11.0
[INFO] [stderr]    Compiling fontconfig-parser v0.5.8
[INFO] [stderr]    Compiling kurbo v0.11.3
[INFO] [stderr]    Compiling polycool v0.4.0
[INFO] [stderr]    Compiling memmap2 v0.9.11
[INFO] [stderr]    Compiling unicode-properties v0.1.4
[INFO] [stderr]    Compiling siphasher v1.0.3
[INFO] [stderr]    Compiling unicode-ccc v0.4.0
[INFO] [stderr]    Compiling unicode-bidi-mirroring v0.4.0
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling unicode-script v0.5.8
[INFO] [stderr]    Compiling png v0.17.16
[INFO] [stderr]    Compiling kurbo v0.13.1
[INFO] [stderr]    Compiling simplecss v0.2.2
[INFO] [stderr]    Compiling zstd-sys v2.0.16+zstd.1.5.7
[INFO] [stderr]    Compiling svgtypes v0.15.3
[INFO] [stderr]    Compiling fontdb v0.23.0
[INFO] [stderr]    Compiling libdeflate-sys v1.25.2
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling imagesize v0.13.0
[INFO] [stderr]    Compiling zune-core v0.5.1
[INFO] [stderr]    Compiling data-url v0.3.2
[INFO] [stderr]    Compiling pico-args v0.5.0
[INFO] [stderr]    Compiling xmlwriter v0.1.0
[INFO] [stderr]    Compiling unicode-vo v0.1.0
[INFO] [stderr]    Compiling pxfm v0.1.30
[INFO] [stderr]    Compiling image-webp v0.2.4
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling zune-jpeg v0.5.15
[INFO] [stderr]    Compiling bytemuck_derive v1.10.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling regex-automata v0.4.16
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling font-types v0.11.3
[INFO] [stderr]    Compiling tiny-skia-path v0.11.4
[INFO] [stderr]    Compiling rustybuzz v0.20.1
[INFO] [stderr]    Compiling read-fonts v0.39.2
[INFO] [stderr]    Compiling moxcms v0.8.1
[INFO] [stderr]    Compiling tiny-skia v0.11.4
[INFO] [stderr]    Compiling rgb v0.8.53
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling zune-jpeg v0.4.21
[INFO] [stderr]    Compiling usvg v0.45.1
[INFO] [stderr]    Compiling libmimalloc-sys v0.1.49
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling png v0.18.1
[INFO] [stderr]    Compiling gif v0.13.3
[INFO] [stderr]    Compiling gif v0.14.2
[INFO] [stderr]    Compiling lexical-write-integer v1.0.6
[INFO] [stderr]    Compiling lexical-parse-integer v1.0.6
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling zlib-rs v0.6.3
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling rustc-hash v2.1.2
[INFO] [stderr]    Compiling noodles-core v0.20.0
[INFO] [stderr]    Compiling bstr v0.2.17
[INFO] [stderr]    Compiling image v0.25.10
[INFO] [stderr]    Compiling resvg v0.45.1
[INFO] [stderr]    Compiling skrifa v0.42.1
[INFO] [stderr]    Compiling write-fonts v0.48.1
[INFO] [stderr]    Compiling zstd-safe v7.2.4
[INFO] [stderr]    Compiling lexical-parse-float v1.0.6
[INFO] [stderr]    Compiling lexical-write-float v1.0.6
[INFO] [stderr]    Compiling serde-value v0.7.0
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling noodles-bgzf v0.47.0
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling safe_arch v1.0.0
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling pdf-writer v0.12.1
[INFO] [stderr]    Compiling bit-vec v0.9.1
[INFO] [stderr]    Compiling anyhow v1.0.103
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling kuva v0.4.0
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling noodles-csi v0.56.0
[INFO] [stderr]    Compiling wide v1.4.0
[INFO] [stderr]    Compiling env_filter v1.0.1
[INFO] [stderr]    Compiling serde-aux v4.7.0
[INFO] [stderr]    Compiling lexical-core v1.0.6
[INFO] [stderr]    Compiling libdeflater v1.25.2
[INFO] [stderr]    Compiling ringbuf v0.5.0
[INFO] [stderr]    Compiling colorous v1.0.16
[INFO] [stderr]    Compiling jiff v0.2.24
[INFO] [stderr]    Compiling bgzf v0.5.0
[INFO] [stderr]    Compiling rawb-io v0.1.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling zstd v0.13.3
[INFO] [stderr]    Compiling fgoxide v0.6.0
[INFO] [stderr]    Compiling noodles-sam v0.85.0
[INFO] [stderr]    Compiling mimalloc v0.1.52
[INFO] [stderr]    Compiling subsetter v0.2.6
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling fgumi-raw-bam v0.2.0
[INFO] [stderr]    Compiling svg2pdf v0.13.0
[INFO] [stderr]    Compiling env_logger v0.11.10
[INFO] [stderr]    Compiling dupblaster v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 3m 16s
[INFO] running `Command { std: "docker" "inspect" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", 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" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling libbz2-rs-sys v0.2.5
[INFO] [stderr]    Compiling noodles-bam v0.90.0
[INFO] [stderr]    Compiling noodles-fasta v0.62.0
[INFO] [stderr]    Compiling png v0.17.16
[INFO] [stderr]    Compiling usvg v0.45.1
[INFO] [stderr]    Compiling png v0.18.1
[INFO] [stderr]    Compiling fgoxide v0.6.0
[INFO] [stderr]    Compiling bzip2 v0.6.1
[INFO] [stderr]    Compiling hybrid-array v0.4.12
[INFO] [stderr]    Compiling tiny-skia v0.11.4
[INFO] [stderr]    Compiling image v0.25.10
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling md-5 v0.11.0
[INFO] [stderr]    Compiling resvg v0.45.1
[INFO] [stderr]    Compiling lzma-rust2 v0.16.4
[INFO] [stderr]    Compiling svg2pdf v0.13.0
[INFO] [stderr]    Compiling noodles-cram v0.94.0
[INFO] [stderr]    Compiling kuva v0.4.0
[INFO] [stderr]    Compiling noodles-util v0.80.0
[INFO] [stderr]    Compiling dupblaster v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 24s
[INFO] running `Command { std: "docker" "inspect" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", 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" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.43s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/dupblaster-97778ffe591ef4f7)
[INFO] [stdout] 
[INFO] [stdout] running 195 tests
[INFO] [stdout] test cigar::tests::trailing_soft_clip ... ok
[INFO] [stdout] test cigar::tests::accepts_a_by_value_iterator ... ok
[INFO] [stdout] test complexity::tests::no_duplicate_final_row_on_exact_boundary ... ok
[INFO] [stdout] test cigar::tests::leading_soft_clip ... ok
[INFO] [stdout] test complexity::tests::pairs_curve_snapshots_every_interval_and_derives_fields ... ok
[INFO] [stdout] test complexity::tests::ladder_path_appends_suffix ... ok
[INFO] [stdout] test complexity::tests::single_end_only_library_reports_single_end ... ok
[INFO] [stdout] test complexity::tests::paired_library_reports_only_pairs_and_ends_at_true_total ... ok
[INFO] [stdout] test cigar::tests::match_only ... ok
[INFO] [stdout] test counts::tests::first_pair_drops_single_end_counts_and_freezes_them ... ok
[INFO] [stdout] test counts::tests::histogram_path_appends_suffix ... ok
[INFO] [stdout] test cigar::tests::both_clips_with_indel ... ok
[INFO] [stdout] test complexity::tests::window_columns_are_marginal_deltas_between_snapshots ... ok
[INFO] [stdout] test counts::tests::pair_histogram_recovers_singletons_by_subtraction ... ok
[INFO] [stdout] test counts::tests::repeated_pair_accumulates_full_count ... ok
[INFO] [stdout] test counts::tests::histogram_rows_report_pairs_for_a_paired_library ... ok
[INFO] [stdout] test counts::tests::histogram_rows_report_single_end_for_a_se_only_library ... ok
[INFO] [stdout] test countset::tests::count_is_observations_not_bumps ... ok
[INFO] [stdout] test counts::tests::counts_saturate_at_u16_max ... ok
[INFO] [stdout] test countset::tests::u32_keys_stay_distinct ... ok
[INFO] [stdout] test dedup::tests::cigar_text_renders_a_single_digit_op_length ... ok
[INFO] [stdout] test dedup::tests::cigar_text_renders_a_zero_op_length ... ok
[INFO] [stdout] test dedup::tests::cigar_text_renders_an_empty_op_list_as_star ... ok
[INFO] [stdout] test dedup::tests::cigar_text_renders_every_op_code ... ok
[INFO] [stdout] test dedup::tests::cigar_text_renders_multi_digit_op_lengths ... ok
[INFO] [stdout] test dedup::tests::cigar_text_renders_the_widest_op_length ... ok
[INFO] [stdout] test dedup::tests::isqrt_ceil_rounds_up_to_the_next_integer_root ... ok
[INFO] [stdout] test dedup::tests::library_index_assigns_a_bucket_per_distinct_library ... ok
[INFO] [stdout] test dedup::tests::library_index_dedups_read_groups_by_library ... ok
[INFO] [stdout] test dedup::tests::library_index_disabled_collapses_to_one_all_reads_bucket ... ok
[INFO] [stdout] test dedup::tests::library_index_is_single_bucket_with_no_libraries ... ok
[INFO] [stdout] test dedup::tests::library_index_is_single_bucket_with_one_library ... ok
[INFO] [stdout] test dedup::tests::library_index_unknown_read_group_maps_to_bucket_zero ... ok
[INFO] [stdout] test dedup::tests::scaled_partition_cap_is_unscaled_for_a_single_library ... ok
[INFO] [stdout] test countset::tests::counts_saturate_at_u16_max ... ok
[INFO] [stdout] test dedup::tests::scaled_partition_cap_never_drops_below_the_floor ... ok
[INFO] [stdout] test counts::tests::empty_library_is_skipped ... ok
[INFO] [stdout] test metrics::tests::frac_duplicates_uses_picard_read_level_formula ... ok
[INFO] [stdout] test metrics::tests::library_size_grows_as_dup_rate_drops ... ok
[INFO] [stdout] test metrics::tests::library_size_handles_extreme_low_dup_rate_without_panicking ... ok
[INFO] [stdout] test metrics::tests::library_size_is_sensible_at_50pct_dup ... ok
[INFO] [stdout] test metrics::tests::library_size_returns_none_when_no_dups ... ok
[INFO] [stdout] test dedup::tests::scaled_partition_cap_shrinks_by_ceil_sqrt_of_library_count ... ok
[INFO] [stdout] test counts::tests::single_end_counted_when_library_has_no_pairs ... ok
[INFO] [stdout] test metrics::tests::library_size_returns_none_when_no_pairs ... ok
[INFO] [stdout] test metrics::tests::sample_empty_when_no_override_and_no_read_groups ... ok
[INFO] [stdout] test metrics::tests::rows_from_stats_emits_one_row_per_nonempty_library ... ok
[INFO] [stdout] test metrics::tests::tsv_header_and_value_have_same_column_count ... ok
[INFO] [stdout] test counts::tests::tsv_header_is_sample_first_with_expected_columns ... ok
[INFO] [stdout] test metrics::tests::sample_override_wins_over_header ... ok
[INFO] [stdout] test metrics::tests::frac_duplicates_is_zero_when_no_mapped_data ... ok
[INFO] [stdout] test plots::tests::count_histogram_path_appends_suffix ... ok
[INFO] [stdout] test metrics::tests::unestimable_library_size_renders_as_empty_cell ... ok
[INFO] [stdout] test plots::tests::empty_rows_writes_no_file ... ok
[INFO] [stdout] test plots::tests::histogram_cutoff_caps_heavy_tails ... ok
[INFO] [stdout] test plots::tests::histogram_cutoff_handles_rows_starting_above_one ... ok
[INFO] [stdout] test plots::tests::histogram_cutoff_never_drops_below_smallest_k ... ok
[INFO] [stdout] test cigar::tests::s_then_h_both_count_as_sclip ... ok
[INFO] [stdout] test complexity::tests::tsv_has_sample_first_and_expected_columns ... ok
[INFO] [stdout] test plots::tests::ladder_plot_path_appends_pdf_suffix ... ok
[INFO] [stdout] test plots::tests::pct_note_floors_tiny_and_formats_plain ... ok
[INFO] [stdout] test plots::tests::marginal_series_drops_partial_final_window ... ok
[INFO] [stdout] test plots::tests::histogram_cutoff_rounds_up_to_ten ... ok
[INFO] [stdout] test plots::tests::plot_title_omits_dash_when_no_sample ... ok
[INFO] [stdout] test raw_writer::tests::a_truncated_compressed_temp_bam_never_reads_back_complete ... ok
[INFO] [stdout] test raw_writer::tests::compressing_the_temp_bam_makes_it_smaller ... ok
[INFO] [stdout] test raw_writer::tests::the_writer_counts_the_records_it_wrote ... ok
[INFO] [stdout] test readname::tests::a_run_of_semicolons_after_a_colon_is_not_read_as_colons ... ok
[INFO] [stdout] test readname::tests::a_semicolon_directly_after_a_colon_is_not_read_as_a_colon ... ok
[INFO] [stdout] test countset::tests::bump_matches_a_shadow_map_over_many_colliding_keys ... ok
[INFO] [stdout] test readname::tests::custom_regex_need_not_be_anchored_to_the_whole_name ... ok
[INFO] [stdout] test readname::tests::custom_regex_extracts_tokens_from_an_undelimited_name ... ok
[INFO] [stdout] test readname::tests::custom_regex_without_a_tile_group_is_rejected ... ok
[INFO] [stdout] test readname::tests::custom_regex_without_an_su_group_is_rejected ... ok
[INFO] [stdout] test readname::tests::custom_regex_that_does_not_match_yields_no_location ... ok
[INFO] [stdout] test readname::tests::different_lanes_of_one_flowcell_are_different_units ... ok
[INFO] [stdout] test readname::tests::illumina_and_element_specs_select_the_same_extractor ... ok
[INFO] [stdout] test readname::tests::illumina_name_yields_flowcell_lane_unit_and_tile ... ok
[INFO] [stdout] test readname::tests::legacy_five_field_illumina_name_is_rejected ... ok
[INFO] [stdout] test readname::tests::mate_suffix_does_not_disturb_the_tile ... ok
[INFO] [stdout] test readname::tests::name_with_empty_flowcell_field_is_rejected ... ok
[INFO] [stdout] test readname::tests::name_with_empty_lane_field_is_rejected ... ok
[INFO] [stdout] test readname::tests::name_with_empty_tile_field_is_rejected ... ok
[INFO] [stdout] test readname::tests::element_aviti_name_parses_with_the_same_layout ... ok
[INFO] [stdout] test readname::tests::name_with_no_colons_is_rejected ... ok
[INFO] [stdout] test readname::tests::parse_error_names_the_read_name_and_the_chosen_format ... ok
[INFO] [stdout] test readname::tests::same_tile_number_on_two_flowcells_yields_different_units ... ok
[INFO] [stdout] test readname::tests::syntactically_invalid_custom_regex_is_rejected ... ok
[INFO] [stdout] test readname::tests::tile_tokens_are_compared_verbatim_without_numeric_normalization ... ok
[INFO] [stdout] test readname::tests::trailing_fields_beyond_the_seventh_are_ignored ... ok
[INFO] [stdout] test readname::tests::unknown_format_spec_is_rejected_and_lists_the_alternatives ... ok
[INFO] [stdout] test sam_reader::parser_tests::b_array_tag_accepts_valid_inputs ... ok
[INFO] [stdout] test sam_reader::parser_tests::b_array_tag_rejects_out_of_range_element ... ok
[INFO] [stdout] test sam_reader::parser_tests::b_array_tag_rejects_unknown_subtype ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_cigar_accepts_max_28bit_length ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_cigar_rejects_28bit_overflow ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_cigar_rejects_zero_length_op ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_i32_handles_min_value ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_i32_rejects_overflow_either_direction ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_u32_accepts_zero ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_u32_rejects_non_digits ... ok
[INFO] [stdout] test sam_reader::parser_tests::parse_u32_rejects_overflow ... ok
[INFO] [stdout] test sam_reader::profile_tests::profile_sam_parse ... ignored
[INFO] [stdout] test sig::tests::bin_for_clamps_positions_beyond_the_padding ... ok
[INFO] [stdout] test sig::tests::bin_for_does_not_clamp_positions_within_the_padding ... ok
[INFO] [stdout] test sig::tests::dm_repeat_detected_as_duplicate ... ok
[INFO] [stdout] test sig::tests::dm_strand_differs_no_collision ... ok
[INFO] [stdout] test sig::tests::drain_is_strand_aware ... ok
[INFO] [stdout] test sig::tests::drain_recovers_both_pair_ends_into_fragment_table ... ok
[INFO] [stdout] test readname::tests::parse_error_for_a_custom_format_quotes_the_pattern ... ok
[INFO] [stdout] test sig::tests::fragment_dup_table_register_and_query ... ok
[INFO] [stdout] test sig::tests::fragment_dup_table_repeat_detected ... ok
[INFO] [stdout] test sig::tests::fragment_dup_table_strand_aware ... ok
[INFO] [stdout] test sig::tests::num_seqs_counts_contigs_plus_unmapped_slot ... ok
[INFO] [stdout] test sig::tests::samblaster_legacy_orphan_fwd_rev_collide ... ok
[INFO] [stdout] test sig::tests::single_end_slot_is_strand_aware_unless_legacy ... ok
[INFO] [stdout] test sig::tests::strand_aware_orphan_fwd_rev_do_not_collide ... ok
[INFO] [stdout] test tests::a_bare_toggle_means_on ... ok
[INFO] [stdout] test sig::tests::strand_aware_orphan_repeat_detected ... ok
[INFO] [stdout] test tests::a_toggle_cannot_be_given_twice ... ok
[INFO] [stdout] test tests::check_crc_and_no_check_crc_are_mutually_exclusive ... ok
[INFO] [stdout] test tests::a_toggle_rejects_a_non_boolean_value ... ok
[INFO] [stdout] test tests::effective_check_crc_defaults_off_for_stdin ... ok
[INFO] [stdout] test tests::effective_check_crc_defaults_on_for_file_input ... ok
[INFO] [stdout] test tests::effective_check_crc_defaults_off_for_stdin_dash ... ok
[INFO] [stdout] test tests::effective_check_crc_false_when_no_check_flag_set ... ok
[INFO] [stdout] test tests::effective_check_crc_true_when_flag_set ... ok
[INFO] [stdout] test tests::max_read_length_accepts_in_range ... ok
[INFO] [stdout] test tests::max_read_length_rejects_non_positive ... ok
[INFO] [stdout] test tests::methylation_mode_defaults_to_none ... ok
[INFO] [stdout] test tests::max_read_length_rejects_overflowing_value ... ok
[INFO] [stdout] test tests::methylation_mode_parses_directional ... ok
[INFO] [stdout] test tests::methylation_mode_rejects_unknown_value ... ok
[INFO] [stdout] test tests::metrics_prefix_is_required ... ok
[INFO] [stdout] test tests::min_bins_rejects_zero_and_oversized ... ok
[INFO] [stdout] test tests::min_bins_accepts_in_range ... ok
[INFO] [stdout] test tests::off_turns_a_toggle_off ... ok
[INFO] [stdout] test tests::short_m_sets_add_mate_tags ... ok
[INFO] [stdout] test tests::short_l_sets_compression_level ... ok
[INFO] [stdout] test tests::toggle_defaults ... ok
[INFO] [stdout] test tests::validate_accepts_bam_extension ... ok
[INFO] [stdout] test tests::validate_accepts_no_output_specified ... ok
[INFO] [stdout] test tests::validate_accepts_stdout_dash ... ok
[INFO] [stdout] test tests::validate_rejects_missing_extension ... ok
[INFO] [stdout] test tests::toggles_accept_the_usual_boolean_spellings ... ok
[INFO] [stdout] test tiles::tests::a_bucket_count_of_zero_still_yields_one_bucket ... ok
[INFO] [stdout] test tests::validate_rejects_non_bam_extension ... ok
[INFO] [stdout] test tiles::tests::a_group_entirely_on_one_tile_is_all_sequencing_duplicates ... ok
[INFO] [stdout] test tiles::tests::a_group_on_one_of_many_tiles_keeps_its_full_raw_count ... ok
[INFO] [stdout] test plots::tests::renders_a_valid_pdf_single_library ... ok
[INFO] [stdout] test tiles::tests::a_library_on_one_tile_has_a_collision_rate_of_one ... ok
[INFO] [stdout] test tiles::tests::a_library_with_no_templates_has_no_tiles_and_no_shares ... ok
[INFO] [stdout] test tiles::tests::a_group_with_one_member_per_tile_is_all_library_duplicates ... ok
[INFO] [stdout] test tiles::tests::a_nearly_empty_spill_can_come_out_larger_than_its_logical_size ... ok
[INFO] [stdout] test plots::tests::renders_one_pdf_for_multiple_libraries ... ok
[INFO] [stdout] test tiles::tests::a_skewed_tile_distribution_raises_the_collision_rate ... ok
[INFO] [stdout] test tiles::tests::a_single_tile_library_reports_no_sequencing_duplicates ... ok
[INFO] [stdout] test tiles::tests::a_spill_record_round_trips_through_its_on_disk_form ... ok
[INFO] [stdout] test tiles::tests::a_spill_record_is_sixteen_bytes ... ok
[INFO] [stdout] test plots::tests::count_histogram_renders_when_all_signatures_exceed_cap ... ok
[INFO] [stdout] test tiles::tests::an_unparseable_read_name_fails_the_spill_rather_than_being_skipped ... ok
[INFO] [stdout] test tiles::tests::a_signature_seen_once_contributes_no_duplicates ... ok
[INFO] [stdout] test tiles::tests::an_unparseable_read_name_is_an_error_naming_the_name ... ok
[INFO] [stdout] test tiles::tests::collision_rate_is_computed_within_a_library_not_across_libraries ... ok
[INFO] [stdout] test tiles::tests::bucket_count_is_clamped_to_the_descriptor_limit ... ok
[INFO] [stdout] test tiles::tests::evenly_used_tiles_have_a_collision_rate_of_one_over_n ... ok
[INFO] [stdout] test tiles::tests::distinct_tiles_intern_to_distinct_ids ... ok
[INFO] [stdout] test plots::tests::count_histogram_single_library_unqualified_name ... ok
[INFO] [stdout] test tiles::tests::ids_are_assigned_serially_from_zero ... ok
[INFO] [stdout] test tiles::tests::ids_reverse_to_the_original_unit_and_tile_names ... ok
[INFO] [stdout] test tiles::tests::one_tile_interns_to_one_id ... ok
[INFO] [stdout] test tiles::tests::same_tile_in_two_libraries_interns_separately ... ok
[INFO] [stdout] test tiles::tests::same_tile_number_on_two_flowcells_interns_separately ... ok
[INFO] [stdout] test tiles::tests::templates_are_counted_per_tile ... ok
[INFO] [stdout] test plots::tests::count_histogram_renders_valid_pdf ... ok
[INFO] [stdout] test tiles::tests::every_bucket_count_reaches_the_same_answer ... ok
[INFO] [stdout] test tiles::tests::signatures_differing_only_in_the_partition_cell_are_different_groups ... ok
[INFO] [stdout] test tiles::tests::groups_do_not_span_libraries ... ok
[INFO] [stdout] test tiles::tests::sequencing_and_library_duplicates_always_sum_to_the_duplicate_total ... ok
[INFO] [stdout] test tiles::tests::the_memo_does_not_change_which_id_is_returned ... ok
[INFO] [stdout] test tiles::tests::the_multi_library_sort_groups_by_library_first ... ok
[INFO] [stdout] test tiles::tests::the_chance_correction_removes_a_split_that_chance_alone_explains ... ok
[INFO] [stdout] test tiles::tests::the_single_library_sort_matches_the_general_one ... ok
[INFO] [stdout] test tiles::tests::the_single_library_sort_orders_by_off_then_sig_then_id ... ok
[INFO] [stdout] test tiles::tests::tiles_are_counted_per_library ... ok
[INFO] [stdout] test plots::tests::count_histogram_multi_library_is_one_faceted_file ... ok
[INFO] [stdout] test tiles::tests::the_collision_rate_and_tile_count_are_reported_with_the_split ... ok
[INFO] [stdout] test tiles::tests::the_reported_split_does_not_depend_on_the_order_templates_arrive_in ... ok
[INFO] [stdout] test tiles::tests::the_chance_correction_is_negligible_when_tiles_are_many ... ok
[INFO] [stdout] test tiles::tests::two_members_on_each_of_two_tiles_is_two_sequencing_and_one_library ... ok
[INFO] [stdout] test tiles::tests::tokens_are_packed_unambiguously_so_a_split_cannot_collide ... ok
[INFO] [stdout] test tiles::tests::zstd_shrinks_a_busy_spill_below_its_logical_size ... ok
[INFO] [stdout] test tiles::tests::the_highest_accepted_level_round_trips_every_record ... ok
[INFO] [stdout] test tiles::tests::a_fast_tier_round_trips_every_record ... ok
[INFO] [stdout] test tiles::tests::the_default_level_round_trips_every_record ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 194 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 3.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_bam.rs (/opt/rustwide/target/debug/deps/test_bam-59d111fec52f182d)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test bam_input_routes_through_pipeline_via_stdin ... ok
[INFO] [stdout] test bam_output_uses_bgzf_framing ... ok
[INFO] [stdout] test bam_input_produces_bam_output_with_matching_dups ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.35s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_complexity_metrics.rs (/opt/rustwide/target/debug/deps/test_complexity_metrics-2f7038f032600708)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test ladder_reports_single_end_for_se_only_input ... ok
[INFO] [stdout] test ladder_reports_pairs_only_for_pe_input_sample_first ... ok
[INFO] [stdout] test histogram_counts_pair_group_sizes ... ok
[INFO] [stdout] test unwritable_metrics_dir_fails_early_with_clear_error ... ok
[INFO] [stdout] test ladder_pairs_curve_snapshots_at_intervals ... ok
[INFO] [stdout] test histogram_single_end_only_input ... ok
[INFO] [stdout] test picard_exact_produces_both_files ... ok
[INFO] [stdout] test count_histogram_multi_library_writes_one_faceted_pdf ... ok
[INFO] [stdout] test se_only_under_picard_approx_is_clean ... ok
[INFO] [stdout] test mixed_library_reports_only_pairs_and_ignores_orphans ... ok
[INFO] [stdout] test writes_pdf_plots_for_both_metrics ... ok
[INFO] [stdout] test pairs_histogram_is_identical_across_all_single_end_strategies ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.14s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_compression.rs (/opt/rustwide/target/debug/deps/test_compression-5a695b9e1652cd62)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test rejects_out_of_range_compression_level ... ok
[INFO] [stdout] test compressed_output_round_trips ... ok
[INFO] [stdout] test default_output_is_uncompressed_bgzf ... ok
[INFO] [stdout] test level_6_produces_smaller_output_than_default ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_dup_propagation.rs (/opt/rustwide/target/debug/deps/test_dup_propagation-1ae6825e2a1be918)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test duplicate_block_propagates_flag_to_supplementary ... ok
[INFO] [stdout] test non_duplicate_block_with_secondary_and_supplementary_keeps_flags_unchanged ... ok
[INFO] [stdout] test duplicate_block_propagates_flag_to_both_secondary_and_supplementary ... ok
[INFO] [stdout] test duplicate_block_propagates_flag_to_secondary ... ok
[INFO] [stderr]      Running tests/test_input_validation.rs (/opt/rustwide/target/debug/deps/test_input_validation-12775e22a4546d0e)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.83s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test normal_reads_do_not_clamp ... ok
[INFO] [stdout] test bumping_max_read_length_avoids_clamping ... ok
[INFO] [stderr]      Running tests/test_library.rs (/opt/rustwide/target/debug/deps/test_library-1177380c5846519e)
[INFO] [stdout] test clip_beyond_padding_clamps_warns_and_exits_nonzero ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.55s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test picard_exact_orphans_dedup_within_library_only ... ok
[INFO] [stdout] test library_unaware_flag_marks_cross_library_dups ... ok
[INFO] [stdout] test same_library_pairs_across_read_groups_are_duplicates ... ok
[INFO] [stdout] test unknown_library_is_distinct_from_named_libraries ... ok
[INFO] [stdout] test cross_library_pairs_at_same_locus_are_not_duplicates ... ok
[INFO] [stdout] test reads_without_read_group_share_unknown_library ... ok
[INFO] [stdout] test stats_reports_one_row_per_library ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.48s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_mate_tags.rs (/opt/rustwide/target/debug/deps/test_mate_tags-f199d04b4d53db2c)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test add_mate_tags_is_idempotent_across_two_runs ... ok
[INFO] [stderr]      Running tests/test_methylation_mode.rs (/opt/rustwide/target/debug/deps/test_methylation_mode-5fc787fa6a02b252)
[INFO] [stdout] test add_mate_tags_writes_mate_cigar_and_mate_mapq ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.47s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test directional_mode_keeps_strand_swapped_cross_contig_pairs_distinct ... ok
[INFO] [stdout] test directional_mode_propagates_dup_flag_to_supplementary ... ok
[INFO] [stdout] test directional_mode_collapses_identical_rr_pairs ... ok
[INFO] [stdout] test directional_mode_collapses_identical_ff_pairs ... ok
[INFO] [stdout] test directional_mode_collapses_same_strand_pcr_duplicates ... ok
[INFO] [stdout] test directional_mode_keeps_opposite_strand_pairs_distinct ... ok
[INFO] [stdout] test directional_mode_collapses_identical_rf_outie_pairs ... ok
[INFO] [stdout] test directional_mode_remove_dups_keeps_both_strands ... ok
[INFO] [stdout] test directional_mode_still_marks_single_end_duplicates ... ok
[INFO] [stdout] test directional_mode_three_deep_keeps_other_strand ... ok
[INFO] [stdout] test wgs_mode_collapses_opposite_strand_pairs_at_same_locus ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_pair_semantics.rs (/opt/rustwide/target/debug/deps/test_pair_semantics-9140ee1e846a151e)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test cross_chromosome_pairs_at_distinct_positions_are_not_dups ... ok
[INFO] [stdout] test remove_dups_drops_duplicate_records_from_output ... ok
[INFO] [stdout] test three_identical_pairs_only_first_kept_as_non_duplicate ... ok
[INFO] [stdout] test cross_chromosome_pairs_at_identical_positions_are_dups ... ok
[INFO] [stdout] test pair_order_swap_does_not_change_duplicate_decision ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.95s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_pg_chain.rs (/opt/rustwide/target/debug/deps/test_pg_chain-dc1fea4bb23e4c22)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test input_with_broken_pp_pointer_fails_with_clear_error ... ok
[INFO] [stdout] test pg_record_has_no_pp_when_input_header_has_no_programs ... ok
[INFO] [stdout] test pg_record_chains_to_one_leaf_when_input_has_two_independent_chains ... ok
[INFO] [stdout] test pg_record_chains_to_previous_leaf_program ... ok
[INFO] [stdout] test rerunning_dupblaster_disambiguates_pg_id ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_picard_exact.rs (/opt/rustwide/target/debug/deps/test_picard_exact-2d9572b64ca5424d)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test picard_exact_pair_before_orphan_marks_orphan_dup ... ok
[INFO] [stdout] test picard_exact_orphan_before_pair_marks_orphan_dup ... ok
[INFO] [stdout] test picard_exact_lone_orphan_not_dup ... ok
[INFO] [stdout] test picard_exact_fragment_keying_is_strand_aware ... ok
[INFO] [stdout] test picard_exact_two_orphans_no_pair_one_marked_dup ... ok
[INFO] [stdout] test picard_exact_respects_tmp_dir_flag ... ok
[INFO] [stdout] test picard_exact_still_marks_pair_pair_duplicates ... ok
[INFO] [stdout] test the_compressed_temp_bam_marks_the_same_duplicates ... ok
[INFO] [stdout] test compressing_the_temp_bam_does_not_change_the_output ... ok
[INFO] [stdout] test compressing_the_temp_bam_at_a_fast_tier_does_not_change_the_output ... ok
[INFO] [stdout] test compressing_the_temp_bam_at_the_highest_level_does_not_change_the_output ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 8.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_query_grouped_check.rs (/opt/rustwide/target/debug/deps/test_query_grouped_check-914782033e3a2e1d)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test fails_when_block_has_only_secondary_records ... ok
[INFO] [stdout] test fails_when_block_has_only_supplementary_records ... ok
[INFO] [stdout] test fails_even_with_ignore_unmated ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_sequencing_duplicates.rs (/opt/rustwide/target/debug/deps/test_sequencing_duplicates-5fabf99397fe59cc)
[INFO] [stdout] 
[INFO] [stdout] running 37 tests
[INFO] [stdout] test a_regex_without_the_required_capture_groups_is_rejected ... ok
[INFO] [stdout] test a_compression_level_above_the_accepted_range_is_rejected ... ok
[INFO] [stdout] test a_compression_level_of_zero_is_rejected_as_ambiguous ... ok
[INFO] [stdout] test a_read_name_the_chosen_format_cannot_parse_is_a_hard_error ... ok
[INFO] [stdout] test an_unknown_read_name_format_is_rejected_before_any_work ... ok
[INFO] [stdout] test a_compressed_run_reports_the_on_disk_size_and_a_ratio ... ok
[INFO] [stdout] test an_uncompressed_spill_reports_the_expected_split ... ok
[INFO] [stdout] test a_custom_regex_format_extracts_the_unit_and_tile ... ok
[INFO] [stdout] test a_fast_tier_spill_reports_the_expected_split ... ok
[INFO] [stdout] test an_uncompressed_run_reports_no_on_disk_size ... ok
[INFO] [stdout] test naming_the_format_explicitly_makes_an_unparseable_name_fatal ... ok
[INFO] [stdout] test a_compressed_spill_reports_the_expected_split ... ok
[INFO] [stdout] test read_names_that_stop_parsing_partway_are_fatal_even_by_default ... ok
[INFO] [stdout] test a_single_tile_library_blanks_the_split ... ok
[INFO] [stdout] test both_pair_fractions_share_a_denominator_so_one_bounds_the_other ... ok
[INFO] [stdout] test duplicates_on_distinct_tiles_are_reported_as_library_duplicates ... ok
[INFO] [stdout] test duplicates_on_one_tile_are_reported_as_sequencing_duplicates ... ok
[INFO] [stdout] test the_library_size_estimate_falls_back_when_the_split_is_not_estimable ... ok
[INFO] [stdout] test the_decomposition_is_reported_per_library ... ok
[INFO] [stdout] test the_library_size_estimate_is_blank_when_every_duplicate_is_a_sequencing_duplicate ... ok
[INFO] [stdout] test sequencing_and_library_duplicates_sum_to_the_duplicate_pair_total ... ok
[INFO] [stdout] test sequencing_duplicate_detection_off_turns_the_split_off ... ok
[INFO] [stdout] test the_highest_accepted_level_reports_the_expected_split ... ok
[INFO] [stdout] test the_output_bam_is_complete_and_readable_with_the_decomposition_on ... ok
[INFO] [stdout] test the_same_tile_number_in_two_lanes_is_not_a_sequencing_duplicate ... ok
[INFO] [stdout] test the_per_sequencing_unit_table_is_written_beside_the_stats_file ... ok
[INFO] [stdout] test the_per_sequencing_unit_table_attributes_sequencing_duplicates_to_its_flowcell ... ok
[INFO] [stdout] test removing_sequencing_duplicates_raises_the_library_size_estimate ... ok
[INFO] [stdout] test the_per_unit_column_reconciles_across_many_heterogeneous_units ... ok
[INFO] [stdout] test unparseable_read_names_fail_the_run_even_with_no_flags ... ok
[INFO] [stdout] test the_per_unit_column_sums_exactly_to_the_raw_per_library_figure ... ok
[INFO] [stdout] test the_same_tile_number_on_two_flowcells_is_not_a_sequencing_duplicate ... ok
[INFO] [stdout] test compressing_both_temp_files_at_once_reports_the_same_numbers ... ok
[INFO] [stdout] test the_split_can_be_skipped_on_a_platform_without_a_preset ... ok
[INFO] [stdout] test the_split_runs_by_default_with_no_flags_at_all ... ok
[INFO] [stdout] test two_copies_on_each_of_two_tiles_yields_two_sequencing_and_one_library ... ok
[INFO] [stdout] test the_split_is_unchanged_under_every_single_end_strategy ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 7.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_single_end_strategy.rs (/opt/rustwide/target/debug/deps/test_single_end_strategy-fc43f410088c6e81)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test samblaster_legacy_two_rev_orphans_same_leftmost_collide ... ok
[INFO] [stdout] test strand_aware_two_rev_orphans_same_position_collide ... ok
[INFO] [stdout] test samblaster_legacy_fwd_and_rev_orphan_same_leftmost_collide ... ok
[INFO] [stdout] test strand_aware_two_fwd_orphans_same_position_collide ... ok
[INFO] [stdout] test picard_approx_still_marks_pair_pair_duplicates ... ok
[INFO] [stdout] test strand_aware_fwd_and_rev_orphan_same_leftmost_do_not_collide ... ok
[INFO] [stdout] test picard_approx_orphan_then_pe_at_same_coord_does_not_mark_orphan_dup ... ok
[INFO] [stdout] test picard_approx_pe_then_orphan_at_same_coord_marks_orphan_dup ... ok
[INFO] [stderr]      Running tests/test_soft_clipping.rs (/opt/rustwide/target/debug/deps/test_soft_clipping-000eccfac703910e)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.91s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test softclip_pushing_five_prime_before_contig_start_does_not_panic ... ok
[INFO] [stdout] test reverse_trailing_softclip_is_compensated_in_signature ... ok
[INFO] [stdout] test forward_leading_softclip_is_compensated_in_signature ... ok
[INFO] [stdout] test different_softclip_lengths_yielding_different_five_prime_are_not_dups ... ok
[INFO] [stdout] test leading_hard_clip_counts_toward_five_prime_clip_like_soft_clip ... ok
[INFO] [stdout] test leading_hard_clip_changes_signature_relative_to_soft_clip_only ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.09s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_startup_banner.rs (/opt/rustwide/target/debug/deps/test_startup_banner-23e6a53a82429508)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test help_no_longer_points_at_the_readme ... ok
[INFO] [stdout] test help_banner_names_fulcrum_genomics_and_the_repository ... ok
[INFO] [stdout] test piped_stdout_is_a_valid_bam_stream ... ok
[INFO] [stdout] test startup_banner_links_to_the_repository ... ok
[INFO] [stdout] test quiet_still_reports_the_duplicate_summary ... ok
[INFO] [stdout] test startup_banner_reports_the_version ... ok
[INFO] [stdout] test quiet_suppresses_the_startup_banner ... ok
[INFO] [stderr]      Running tests/test_stats.rs (/opt/rustwide/target/debug/deps/test_stats-1c883e0ee1fa64ad)
[INFO] [stdout] test startup_banner_credits_fulcrum_genomics ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.16s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test stats_tsv_handles_empty_input_without_crashing ... ok
[INFO] [stdout] test stats_counts_unaffected_by_remove_dups ... ok
[INFO] [stdout] test stats_tsv_has_all_expected_columns ... ok
[INFO] [stdout] test an_attached_metrics_prefix_is_not_duplicated_by_the_runner ... ok
[INFO] [stdout] test paired_unmapped_singleton_counts_as_unmapped_orphan_not_unmated ... ok
[INFO] [stdout] test decomposition_columns_are_blank_when_the_split_is_disabled ... ok
[INFO] [stdout] test stats_tsv_reports_correct_counts_for_simple_dup_input ... ok
[INFO] [stdout] test stats_tsv_pulls_sample_from_read_group_sm ... ok
[INFO] [stdout] test stats_tsv_comma_joins_multiple_sm_values ... ok
[INFO] [stdout] test stats_tsv_uses_sample_override ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.81s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_stress.rs (/opt/rustwide/target/debug/deps/test_stress-f5e5dbb3ff32ad20)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test ten_thousand_pairs_with_thousand_dups ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.28s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_unmapped_orphan_semantics.rs (/opt/rustwide/target/debug/deps/test_unmapped_orphan_semantics-0dd4c931964329e6)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test both_unmapped_pairs_are_never_marked_duplicate ... ok
[INFO] [stdout] test unmapped_single_end_read_passes_through_in_picard_exact_mode ... ok
[INFO] [stdout] test two_reverse_strand_single_end_orphans_at_different_positions_are_not_dups ... ok
[INFO] [stdout] test orphan_pairs_with_different_mapped_positions_are_not_dups ... ok
[INFO] [stdout] test two_orphan_pairs_with_same_mapped_position_are_dups ... ok
[INFO] [stdout] test two_reverse_strand_single_end_orphans_at_same_position_are_dups ... ok
[INFO] [stdout] test unmapped_single_end_read_passes_through_without_ignore_unmated ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.14s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_unmated.rs (/opt/rustwide/target/debug/deps/test_unmated-5ad7360c87999426)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test single_paired_primary_with_no_mate_aborts_without_ignore_unmated ... ok
[INFO] [stdout] test single_paired_primary_with_no_mate_succeeds_under_ignore_unmated ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.26s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_usage_footer.rs (/opt/rustwide/target/debug/deps/test_usage_footer-17036e3327bc342d)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test footer_is_printed_without_quiet ... ok
[INFO] [stderr]      Running tests/test_version.rs (/opt/rustwide/target/debug/deps/test_version-4e444843c9636dfd)
[INFO] [stdout] test footer_is_suppressed_with_quiet ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.35s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test version_long_flag_prints_to_stdout_and_exits_zero ... ok
[INFO] [stdout] test version_short_flag_matches_long_flag ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100", kill_on_drop: false }`
[INFO] [stdout] 26ae2eb6bec105997e3067f092f4215f401552ff7c8551e9fbd135fb097c7100
