[INFO] cloning repository https://github.com/JoseBlanca/join_per_sample_vcfs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/JoseBlanca/join_per_sample_vcfs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FJoseBlanca%2Fjoin_per_sample_vcfs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FJoseBlanca%2Fjoin_per_sample_vcfs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 2d6c4cbbf652c273f2b0371e9492d0eaeae3aef0
[INFO] testing JoseBlanca/join_per_sample_vcfs against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FJoseBlanca%2Fjoin_per_sample_vcfs" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/JoseBlanca/join_per_sample_vcfs
[INFO] finished tweaking git repo https://github.com/JoseBlanca/join_per_sample_vcfs
[INFO] tweaked toml for git repo https://github.com/JoseBlanca/join_per_sample_vcfs written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/JoseBlanca/join_per_sample_vcfs on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/JoseBlanca/join_per_sample_vcfs 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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ba64b194206620c8c28c22ebb750e86f833faa79a204f4a039dc211bd041776e
[INFO] running `Command { std: "docker" "start" "-a" "ba64b194206620c8c28c22ebb750e86f833faa79a204f4a039dc211bd041776e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ba64b194206620c8c28c22ebb750e86f833faa79a204f4a039dc211bd041776e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ba64b194206620c8c28c22ebb750e86f833faa79a204f4a039dc211bd041776e", kill_on_drop: false }`
[INFO] [stdout] ba64b194206620c8c28c22ebb750e86f833faa79a204f4a039dc211bd041776e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cda0e87963afcd19e1c634e5667e5ed2eb5e193527f835ef48bdebc0f2570cf1
[INFO] running `Command { std: "docker" "start" "-a" "cda0e87963afcd19e1c634e5667e5ed2eb5e193527f835ef48bdebc0f2570cf1", kill_on_drop: false }`
[INFO] [stderr]    Compiling lexical-util v1.0.7
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling typenum v1.20.0
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling zlib-rs v0.6.3
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling hashbrown v0.17.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling bit-vec v0.9.1
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling zerocopy v0.8.39
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling rustix v1.1.2
[INFO] [stderr]    Compiling syn v2.0.115
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling libbz2-rs-sys v0.2.3
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling lru v0.16.3
[INFO] [stderr]    Compiling lexical-write-integer v1.0.6
[INFO] [stderr]    Compiling lexical-parse-integer v1.0.6
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling lexical-parse-float v1.0.6
[INFO] [stderr]    Compiling lexical-write-float v1.0.6
[INFO] [stderr]    Compiling bzip2 v0.6.1
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling lexical-core v1.0.6
[INFO] [stderr]    Compiling noodles-core v0.19.0
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling tempfile v3.23.0
[INFO] [stderr]    Compiling lzma-rust2 v0.16.2
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling noodles-bgzf v0.46.0
[INFO] [stderr]    Compiling noodles-csi v0.55.0
[INFO] [stderr]    Compiling noodles-fasta v0.60.0
[INFO] [stderr]    Compiling noodles-sam v0.84.0
[INFO] [stderr]    Compiling noodles-bam v0.88.0
[INFO] [stderr]    Compiling noodles-cram v0.92.0
[INFO] [stderr]    Compiling merge_per_sample_vcfs v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 29.80s
[INFO] running `Command { std: "docker" "inspect" "cda0e87963afcd19e1c634e5667e5ed2eb5e193527f835ef48bdebc0f2570cf1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cda0e87963afcd19e1c634e5667e5ed2eb5e193527f835ef48bdebc0f2570cf1", kill_on_drop: false }`
[INFO] [stdout] cda0e87963afcd19e1c634e5667e5ed2eb5e193527f835ef48bdebc0f2570cf1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b28775d3a2a35e833249ce12520b79406e12c3c8397808e652a9e350cfb08164
[INFO] running `Command { std: "docker" "start" "-a" "b28775d3a2a35e833249ce12520b79406e12c3c8397808e652a9e350cfb08164", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling zerocopy v0.8.39
[INFO] [stderr]    Compiling syn v2.0.115
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling tempfile v3.23.0
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling zerocopy-derive v0.8.39
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling noodles-core v0.19.0
[INFO] [stderr]    Compiling noodles-csi v0.55.0
[INFO] [stderr]    Compiling noodles-fasta v0.60.0
[INFO] [stderr]    Compiling noodles-sam v0.84.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling noodles-bam v0.88.0
[INFO] [stderr]    Compiling noodles-cram v0.92.0
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling merge_per_sample_vcfs v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 34.44s
[INFO] running `Command { std: "docker" "inspect" "b28775d3a2a35e833249ce12520b79406e12c3c8397808e652a9e350cfb08164", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b28775d3a2a35e833249ce12520b79406e12c3c8397808e652a9e350cfb08164", kill_on_drop: false }`
[INFO] [stdout] b28775d3a2a35e833249ce12520b79406e12c3c8397808e652a9e350cfb08164
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6c7ca2c7d5aaf75710cec3c6fce04d6d04c17438db286713b250a35da82c1508
[INFO] running `Command { std: "docker" "start" "-a" "6c7ca2c7d5aaf75710cec3c6fce04d6d04c17438db286713b250a35da82c1508", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.24s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/merge_per_sample_vcfs-fb83b6755692cb05)
[INFO] [stdout] 
[INFO] [stdout] running 235 tests
[INFO] [stdout] test buffered_peekable::tests::all_ok_in_order ... ok
[INFO] [stdout] test buffered_peekable::tests::buffer_size_larger_than_stream ... ok
[INFO] [stdout] test buffered_peekable::tests::buffer_size_one_works ... ok
[INFO] [stdout] test buffered_peekable::tests::error_first_then_recovers ... ok
[INFO] [stdout] test buffered_peekable::tests::next_after_exhaustion_keeps_returning_none ... ok
[INFO] [stdout] test buffered_peekable::tests::peek_consumes_error_only_once ... ok
[INFO] [stdout] test buffered_peekable::tests::peek_does_not_consume_record ... ok
[INFO] [stdout] test buffered_peekable::tests::peek_then_next_returns_same_record ... ok
[INFO] [stdout] test buffered_peekable::tests::single_error_preserves_order ... ok
[INFO] [stdout] test buffered_peekable::tests::next_surfaces_error_when_called_directly ... ok
[INFO] [stdout] test buffered_peekable::tests::multiple_errors_each_surface_in_turn ... ok
[INFO] [stdout] test buffered_peekable::tests::empty_iterator_yields_none_forever ... ok
[INFO] [stdout] test decompression_pool::tests::test_empty_reader ... ok
[INFO] [stdout] test decompression_pool::tests::test_reader_drop_cancels_job ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_enumerate_genotypes_diploid_triallelic ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_enumerate_genotypes_diploid_biallelic ... ok
[INFO] [stdout] test decompression_pool::tests::test_multiple_readers_concurrent ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_all_hom_ref_gets_low_qual ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_clear_het_gets_high_qual ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_f_zero_equals_hardy_weinberg ... ok
[INFO] [stdout] test decompression_pool::tests::test_pool_drop_with_active_readers ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_multinomial_coefficients ... ok
[INFO] [stdout] test decompression_pool::tests::test_error_propagation ... ok
[INFO] [stdout] test decompression_pool::tests::test_single_reader_small_data ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_high_f_favors_homozygotes ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_enumerate_genotypes_tetraploid_biallelic ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_f_one_gives_zero_het_prior ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_prior_pseudocounts ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_synthetic_pls_feed_into_em ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_synthetic_pls_missing_genotype ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_one_het_one_ref_moderate_qual ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_synthetic_pls_tetraploid ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a10_all_optional_flag_drops_disabled_keeps_only_unconditional_drops ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a11_min_read_length_drops_short_and_empty ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_tetraploid_posteriors_sum_to_one ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_synthetic_pls_diploid_het ... ok
[INFO] [stdout] test decompression_pool::tests::test_gzip_decompression ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_tetraploid_clear_variant ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_posteriors_sum_to_one ... ok
[INFO] [stdout] test genotype_posteriors::tests::test_synthetic_pls_diploid_hom_ref ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a12_filter_precedence_is_hit_rate_ordered ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a3_tiebreaker_on_equal_coordinates ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a4_out_of_order_within_a_single_stream ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a2_multi_stream_merge_order ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a5_duplicate_read_across_streams ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a13_empty_stream ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a14b_truly_unmapped_record_is_filtered_not_errored ... ok
[INFO] [stdout] test decompression_pool::tests::test_many_readers_few_threads ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a14_mixed_empty_and_non_empty ... ok
[INFO] [stdout] test decompression_pool::tests::test_single_reader_large_data ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a15_position_above_u32_max_round_trips_through_mapped_read ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a1_single_stream_pass_through ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a4b_iterator_returns_none_after_first_error ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a7_min_mapq_filter ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a6_dedup_buffer_clears_on_locus_advance ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a7b_missing_mapq_is_treated_as_zero_and_filtered_by_default ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a8_min_mapq_none_disables ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a9_each_flag_drop_one_at_a_time ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_bq_floor_excludes_low_quality_mismatches ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_default_config_has_filter_enabled_at_ten_percent ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_bq_floor_zero_counts_every_mismatch ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b2_cram_4x_rejected_at_header_time ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_n_in_read_skipped_from_both_numerator_and_denominator ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_high_mismatch_drops ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_indels_advance_cursors_but_dont_count ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_no_m_op_returns_false ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::a16_empty_inputs_returns_no_inputs ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_filter_counts_default_high_mismatch_fraction_is_zero ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_n_in_ref_skipped_from_both_numerator_and_denominator ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_soft_clips_excluded_from_denominator ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_indel_already_at_leftmost_position_no_shift ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_indel_at_first_op_already_no_shift ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_multi_indel_cigar_each_indel_aligned_independently ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_no_following_m_op_skips_shift ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_threshold_boundary_is_strict_greater_than ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_non_homopolymer_insertion_does_not_shift ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f1_zero_mismatches_passes ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_adjacent_indels_left_alone ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_homopolymer_deletion_shifts_to_leftmost_position ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_homopolymer_insertion_partial_shift ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_homopolymer_insertion_shifts_to_leftmost_position ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_indel_after_soft_clip_does_not_shift_through_clip ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_non_homopolymer_deletion_does_not_shift ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b6_fasta_agreement ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b4_sample_tag_handling ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_orientation_invariance_forward_and_reverse_converge ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_tandem_repeat_multi_base_insertion_shifts_by_unit ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::f3_two_reads_different_anchors_converge_after_left_align ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_ancient_dna_short_fragment_yields_boundary_inside_read_span ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_default_record_has_no_boundary ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_mate_unmapped_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b3_non_coordinate_sort_rejected ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b7_end_to_end_smoke ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_molecule_at_least_as_long_as_read_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b5_contig_list_mismatch_across_crams ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_mate_on_different_contig_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_forward_strand_short_insert_boundary_is_start_plus_tlen ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_negative_tlen_takes_absolute_value ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_reverse_strand_short_insert_boundary_is_mate_start_minus_one ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_same_strand_pair_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_zero_tlen_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_self_unmapped_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g1_single_end_returns_none ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_consecutive_id_is_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_first_op_insertion_is_not_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_first_op_deletion_record_is_dropped_with_counter ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_last_op_deletion_after_trailing_clip_is_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_last_op_deletion_is_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_consecutive_same_kind_indels_are_not_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_consecutive_id_record_is_dropped_with_counter ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_first_op_deletion_after_clip_is_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_first_op_deletion_is_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b1_header_parsing_on_known_good_cram ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_well_formed_cigar_is_not_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::p1_contig_list_md5_wildcard_equality ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_well_formed_record_passes_through ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_only_clips_is_not_bad ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::p2_malformed_record_message_omits_empty_qname ... ok
[INFO] [stdout] test per_sample_caller::pileup::active_set::tests::expire_passed_drops_only_reads_behind_walker ... ok
[INFO] [stdout] test per_sample_caller::pileup::active_set::tests::expire_uses_swap_remove_and_keeps_index_consistent ... ok
[INFO] [stdout] test per_sample_caller::pileup::active_set::tests::secondary_index_maps_read_id_to_correct_entry ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::auto_select_picks_mode_by_op_count ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::g2_empty_cigar_is_not_bad ... ok
[INFO] [stdout] test per_sample_caller::pileup::active_set::tests::admit_assigns_increasing_read_ids ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::cursor_matches_decompose_on_pattern_corpus ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::cursor_queries_are_stateless ... ok
[INFO] [stdout] test per_sample_caller::pileup::active_set::tests::flush_all_releases_every_slot ... ok
[INFO] [stdout] test per_sample_caller::pileup::active_set::tests::paired_reads_get_mate_read_id_cross_links ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::deletion_anchored_before_window_overlaps_but_not_anchored ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::f5_decompose_oracle_also_skips_read_n ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::g1_no_boundary_means_no_filtering ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::events_overlapping_subsets_correctly ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::f5_read_n_emits_no_match_event_at_n_ref ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::deletion_in_middle_is_anchored_at_preceding_m ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::indel_bq_proxy_takes_min_of_window ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::insertion_in_middle_is_anchored_at_preceding_m ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::f5_read_n_emits_no_match_event_at_atgc_ref ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::consecutive_indels_produce_two_events ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::events_at_picks_only_walker_pos_events ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::g1_decompose_oracle_also_skips_adaptor_bases ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::f5_read_n_skip_is_per_position_not_per_read ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::first_op_indel_is_dropped_but_rest_of_read_keeps_events ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::g1_forward_strand_drops_match_events_at_or_past_boundary ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::deletion_bq_proxy_uses_flanking_bases ... ok
[INFO] [stdout] test per_sample_caller::pileup::cigar_cursor::tests::g1_reverse_strand_drops_match_events_at_or_before_boundary ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::last_op_indel_is_dropped ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::padding_and_hard_clip_are_no_ops ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::pure_match_yields_one_match_event_per_position ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::n_op_skips_reference_and_produces_no_event ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::snp_pattern_via_eq_x ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::apply_events_deletion_drops_bases_after_anchor ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::apply_events_match_before_insertion_at_same_anchor_emits_read_base_then_inserted ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::drain_aged_emits_in_coordinate_order ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::soft_clip_then_indel_at_alignment_start_one_drops_indel ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::apply_events_insertion_appends_inserted_bases_after_anchor ... ok
[INFO] [stdout] test per_sample_caller::pileup::decompose::tests::soft_clip_then_indel_away_from_chrom_start_keeps_indel ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::zero_event_bq_zeros_match_only_preserving_indel_proxies ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::widen_extends_ref_seq_and_existing_alleles ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::apply_events_pure_match_yields_unchanged_ref ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::apply_events_snp_replaces_one_base ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::find_overlapping_returns_record_when_event_falls_inside_footprint ... ok
[INFO] [stdout] test per_sample_caller::cram_input::tests::b4b_malformed_md5_rejected ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::find_overlapping_walks_past_intermediate_narrow_record_to_wide_one ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::find_or_create_allele_returns_same_bucket_on_match ... ok
[INFO] [stdout] test per_sample_caller::pileup::open_record::tests::open_new_creates_record_with_ref_allele_zero_obs ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::lowest_free_slot_is_reused_first ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::drain_lifecycle_marks_emits_both_for_transient_slot_within_one_drain ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::first_mate_registers_then_second_mate_reuses_slot ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::release_slot_error_carries_walker_context ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::release_pending_partner_paired_with_release_slot_emits_expired_for_orphan ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::reset_clears_state_but_preserves_counters ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::solo_read_emits_new_then_expired_across_two_drains ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::solo_read_allocates_slot_and_does_not_register_qname ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::slot_releases_only_after_both_mates_exit ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::same_emission_reuse_does_not_collide_on_slot_id ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::release_pending_partner_is_noop_for_solo_read ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::forward_strand_count_recorded_correctly ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::g1_walker_drops_match_observations_past_adaptor_boundary ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::lifecycle_markers_appear_on_emitted_records ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::mate_overlap_agree_combined_bq_caps_at_200 ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::column_depth_cap_truncates_snp_only_column_when_over_cap ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::mate_overlap_agree_keeper_carries_summed_bq ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::insertion_record_has_alt_longer_than_ref ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::deletion_record_has_extended_ref_span ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::deletion_record_does_not_double_count_ref_reads ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::column_depth_cap_uses_indel_cap_when_any_indel_event_present ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::column_depth_cap_keeps_first_n_of_admission_order ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::release_pending_partner_is_noop_for_partnered_pair ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::chromosome_id_regression_is_a_hard_error ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::forward_chromosome_change_is_accepted ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::stale_pending_mates_are_evicted_after_window ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::column_depth_cap_does_not_fire_below_threshold ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::mate_overlap_disagree_winner_bq_scaled_by_0_8 ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::out_of_order_input_is_a_hard_error ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::orphan_first_mate_emits_balanced_lifecycle_marks ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::paired_mate_indel_overlap_yields_single_observation ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::pure_ref_pileup_emits_one_record_per_position_with_only_ref_allele ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::mate_overlap_zeroes_lower_bq_contribution ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::uncovered_positions_produce_no_records ... ok
[INFO] [stdout] test per_sample_caller::ref_fetcher::tests::fetch_past_contig_end_returns_unexpected_eof ... ok
[INFO] [stdout] test per_sample_caller::ref_fetcher::tests::contig_name_in_fasta_must_match_contig_list ... ok
[INFO] [stdout] test per_sample_caller::ref_fetcher::tests::start_1based_zero_is_rejected ... ok
[INFO] [stdout] test per_sample_caller::ref_fetcher::tests::cache_evicts_previous_chromosome_on_chrom_change ... ok
[INFO] [stdout] test vcf_writer::tests::test_broken_pipe_immediately_on_flush ... ok
[INFO] [stdout] test per_sample_caller::ref_fetcher::tests::unknown_chrom_id_returns_invalid_input ... ok
[INFO] [stdout] test per_sample_caller::ref_fetcher::tests::fetch_returns_bases_at_correct_offset ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::snp_at_one_position_emits_record_with_two_alleles ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::record_widen_events_counter_only_increments_on_real_widens ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::placed_left_and_placed_start_are_per_record ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::mate_overlap_bq_tie_prefers_first_mate_not_earlier_position ... ok
[INFO] [stdout] test vcf_writer::tests::test_broken_pipe_on_write_variant_does_not_error ... ok
[INFO] [stdout] test vcf_writer::tests::test_broken_pipe_on_write_variants_stops_silently ... ok
[INFO] [stdout] test vcf_writer::tests::test_hom_alt ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::paired_mates_share_chain_slot_id ... ok
[INFO] [stdout] test vcf_writer::tests::test_hom_ref ... ok
[INFO] [stdout] test vcf_writer::tests::test_multiple_variants ... ok
[INFO] [stdout] test vcf_writer::tests::test_partial_missing_genotype ... ok
[INFO] [stdout] test vcf_writer::tests::test_single_sample_phased_het ... ok
[INFO] [stdout] test vcf_writer::tests::test_header_format ... ok
[INFO] [stdout] test vcf_writer::tests::test_multi_sample ... ok
[INFO] [stdout] test vcf_writer::tests::test_broken_pipe_mid_variant_stops_silently ... ok
[INFO] [stdout] test vcf_writer::tests::test_write_single_variant ... ok
[INFO] [stdout] test vcf_writer::tests::test_missing_genotype ... ok
[INFO] [stdout] test vcf_writer::tests::test_single_sample_het ... ok
[INFO] [stdout] test vcf_writer::tests::test_write_variants_from_iterator ... ok
[INFO] [stdout] test vcf_writer::tests::test_write_to_buffer ... ok
[INFO] [stdout] test vcf_writer::tests::test_write_variants_error_propagation ... ok
[INFO] [stdout] test vcf_writer::tests::test_write_to_plain_file ... ok
[INFO] [stdout] test per_sample_caller::pileup::tests::record_emits_in_coordinate_order_across_reads ... ok
[INFO] [stdout] test vcf_writer::tests::test_write_to_gzipped_file ... ok
[INFO] [stdout] test vcf_writer::tests::test_no_alt_allele ... ok
[INFO] [stdout] test buffered_peekable::tests::with_buffer_size_zero_panics - should panic ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::high_water_warning_fires_once_at_threshold_and_then_stays_set ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::reset_preserves_high_water_warning_flag ... ok
[INFO] [stdout] test per_sample_caller::pileup::slot_allocator::tests::slot_exhausted_returns_hard_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 235 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.54s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/merge_per_sample_vcfs-fa8125c403d1f3dd)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/genotype_merging_test.rs (/opt/rustwide/target/debug/deps/genotype_merging_test-5db8627f8ab3bc9c)
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test test_deletion_len_2 ... ok
[INFO] [stdout] test test_missing_allele_in_merged_haplotype ... ok
[INFO] [stdout] test test_phase_broken_between_hets_missing_genotype ... ok
[INFO] [stdout] test test_het_deletion ... ok
[INFO] [stdout] test test_overlapping_deletions ... ok
[INFO] [stdout] test test_phase_broken_in_one_sample ... ok
[INFO] [stdout] test test_phase_false_on_first_position_still_solvable ... ok
[INFO] [stdout] test test_phase_conserved_in_het ... ok
[INFO] [stdout] test test_phase_kept_between_hets ... ok
[INFO] [stdout] test test_phase_lost_in_het2 ... ok
[INFO] [stdout] test test_phase_lost_in_het_and_not_recovered ... ok
[INFO] [stdout] test test_phase_maintained_despited_not_phased_in_hom_position ... ok
[INFO] [stdout] test test_non_variant_vars_are_removed ... ok
[INFO] [stdout] test test_grouper_preserves_genomic_order ... ok
[INFO] [stdout] test test_phase_single_het_no_phase_needed ... ok
[INFO] [stdout] test test_simple_deletion ... ok
[INFO] [stdout] test test_simple_merge ... ok
[INFO] [stdout] test test_phase_first_het_not_at_first_position ... ok
[INFO] [stdout] test test_simple_insertion ... ok
[INFO] [stdout] test test_phase_lost_in_het ... ok
[INFO] [stdout] test test_analyze_single_group_from_merged_bin ... ok
[INFO] [stdout] test test_two_overlapping_deletions_in_same_sample ... ok
[INFO] [stdout] test test_two_deletions_in_same_sample ... ok
[INFO] [stdout] test test_allele_merge_with_non_ref_filtered ... ok
[INFO] [stdout] test test_group_merging_creates_correct_number_of_vars ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/gvcf_parser_test.rs (/opt/rustwide/target/debug/deps/gvcf_parser_test-f8c0e9f99571d9a1)
[INFO] [stdout] 
[INFO] [stdout] running 26 tests
[INFO] [stdout] test test_genotype_parsing_basic ... ok
[INFO] [stdout] test test_dot_alt_not_included_in_alleles ... ok
[INFO] [stdout] test test_genotype_parsing_missing ... ok
[INFO] [stdout] test test_get_span_returns_error_on_position_overflow ... ok
[INFO] [stdout] test test_genotype_with_format_fields ... ok
[INFO] [stdout] test test_gvcf_parsing ... ok
[INFO] [stdout] test test_g_vcf_record ... ok
[INFO] [stdout] test test_genotype_parsing_multiallelic ... ok
[INFO] [stdout] test test_genotype_performance_with_sample_gvcf ... ok
[INFO] [stdout] test test_haploid_parsing ... ok
[INFO] [stdout] test test_non_ref_not_included_in_alleles ... ok
[INFO] [stdout] test test_parse_rejects_consecutive_separators ... ok
[INFO] [stdout] test test_parse_rejects_invalid_characters ... ok
[INFO] [stdout] test test_parse_rejects_trailing_junk ... ok
[INFO] [stdout] test test_parse_rejects_leading_separator ... ok
[INFO] [stdout] test test_gzip_path ... ok
[INFO] [stderr]      Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-74968aed72b77e5c)
[INFO] [stdout] test test_gzip_reader ... ok
[INFO] [stdout] test test_peek_variant_exhausted ... ok
[INFO] [stdout] test test_peek_variant ... ok
[INFO] [stdout] test test_ploidy_mismatch_rejected ... ok
[INFO] [stdout] test test_ref_allele_len ... ok
[INFO] [stdout] test test_parse_rejects_allele_index_out_of_range ... ok
[INFO] [stdout] test test_sample_parsing ... ok
[INFO] [stdout] test test_sample_parsing_with_buffer ... ok
[INFO] [stdout] test test_sample_parsing_single_sample ... ok
[INFO] [stdout] test test_tetraploid_parsing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test test_all_ref_samples_produce_no_variants ... ok
[INFO] [stdout] test test_del4 ... ok
[INFO] [stdout] test test_del1 ... ok
[INFO] [stdout] test test_del3 ... ok
[INFO] [stdout] test test_multi_chromosome_pipeline ... ok
[INFO] [stdout] test test_deletion_overlapping_with_snps ... ok
[INFO] [stdout] test test_template_two_samples_non_ref_snps ... ok
[INFO] [stdout] test test_full_pipeline_two_samples ... ok
[INFO] [stdout] test test_del_ins_missing_phase ... ok
[INFO] [stdout] test test_missing_genotype_propagation ... ok
[INFO] [stdout] test test_snps_some_phases ... ok
[INFO] [stdout] test test_missing_phase3 ... ok
[INFO] [stdout] test test_missing_phase1 ... ok
[INFO] [stdout] test test_del2 ... ok
[INFO] [stdout] test test_template_two_samples_snps ... ok
[INFO] [stdout] test test_missing_phase2 ... ok
[INFO] [stdout] test test_del_ins ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/utils_magic_test.rs (/opt/rustwide/target/debug/deps/utils_magic_test-0b4d25e7fd53d31c)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test are_gzipped_magic_bytes_detects_gzip_header ... ok
[INFO] [stdout] test are_gzipped_magic_bytes_detects_non_gzip_header ... ok
[INFO] [stdout] test are_gzipped_magic_bytes_returns_error_for_insufficient_bytes ... ok
[INFO] [stdout] test file_is_gzipped_returns_error_for_short_file ... ok
[INFO] [stdout] test file_is_gzipped_returns_true_for_file_with_gzip_magic_bytes ... ok
[INFO] [stdout] test file_is_gzipped_works_with_paths_from_fs_module ... ok
[INFO] [stdout] test file_is_gzipped_propagates_io_error_for_missing_file ... ok
[INFO] [stdout] test file_is_gzipped_returns_false_for_plain_file ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/variant_group_test.rs (/opt/rustwide/target/debug/deps/variant_group_test-a2a5d883b0192da4)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test test_duplicate_samples ... ok
[INFO] [stdout] test test_empty_input ... ok
[INFO] [stdout] test test_single_variant_group ... ok
[INFO] [stdout] test test_source_iter_samples ... ok
[INFO] [stdout] test test_non_variable_groups_are_skipped ... ok
[INFO] [stdout] test test_binning_with_deletion_spanning_several_snps ... ok
[INFO] [stdout] test test_simple_binning ... ok
[INFO] [stdout] test test_skip_path_produces_fewer_groups ... ok
[INFO] [stdout] test test_no_matching_chromosomes ... ok
[INFO] [stdout] test test_wrong_order ... ok
[INFO] [stdout] test test_wrong_chrom_order ... ok
[INFO] [stdout] test test_bin_contains_expected_variants ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests merge_per_sample_vcfs
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/gvcf_parser.rs - gvcf_parser (line 6) - compile ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/gvcf_parser.rs - gvcf_parser (line 6) stdout ----
[INFO] [stdout] error[E0061]: this function takes 2 arguments but 1 argument was supplied
[INFO] [stdout]    --> src/gvcf_parser.rs:12:14
[INFO] [stdout]     |
[INFO] [stdout]  12 | let parser = VarIterator::from_gzip_path("sample.g.vcf.gz")?;
[INFO] [stdout]     |              ^^^^^^^^^^^^^^^^^^^^^^^^^^^------------------- argument #2 of type `u8` is missing
[INFO] [stdout]     |
[INFO] [stdout] note: associated function defined here
[INFO] [stdout]    --> src/gvcf_parser.rs:741:12
[INFO] [stdout]     |
[INFO] [stdout] 741 |     pub fn from_gzip_path<P: AsRef<Path>>(path: P, ploidy: u8) -> VcfResult<Self> {
[INFO] [stdout]     |            ^^^^^^^^^^^^^^
[INFO] [stdout] help: provide the argument
[INFO] [stdout]     |
[INFO] [stdout]  12 | let parser = VarIterator::from_gzip_path("sample.g.vcf.gz", /* u8 */)?;
[INFO] [stdout]     |                                                           ++++++++++
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] For more information about this error, try `rustc --explain E0061`.
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/gvcf_parser.rs - gvcf_parser (line 6)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.20s; merged doctests compilation took 0.11s
[INFO] [stderr] error: doctest failed, to rerun pass `--doc`
[INFO] running `Command { std: "docker" "inspect" "6c7ca2c7d5aaf75710cec3c6fce04d6d04c17438db286713b250a35da82c1508", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6c7ca2c7d5aaf75710cec3c6fce04d6d04c17438db286713b250a35da82c1508", kill_on_drop: false }`
[INFO] [stdout] 6c7ca2c7d5aaf75710cec3c6fce04d6d04c17438db286713b250a35da82c1508
