[INFO] cloning repository https://github.com/mbhall88/rasusa
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/mbhall88/rasusa" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmbhall88%2Frasusa", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmbhall88%2Frasusa'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] fa644f0821052d92525bf2a0410b4d521adee83f
[INFO] testing mbhall88/rasusa against master#b70888601af92f6cdc0364abab3446e418b91d36 for pr-86479
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmbhall88%2Frasusa" "/workspace/builds/worker-10/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-10/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/mbhall88/rasusa on toolchain b70888601af92f6cdc0364abab3446e418b91d36
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/mbhall88/rasusa
[INFO] finished tweaking git repo https://github.com/mbhall88/rasusa
[INFO] tweaked toml for git repo https://github.com/mbhall88/rasusa written to /workspace/builds/worker-10/source/Cargo.toml
[INFO] crate git repo https://github.com/mbhall88/rasusa already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8f5860738fd0f3f2568f461f63fef5de33c71abd866605a85d2edf3624d7f8f8
[INFO] running `Command { std: "docker" "start" "-a" "8f5860738fd0f3f2568f461f63fef5de33c71abd866605a85d2edf3624d7f8f8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8f5860738fd0f3f2568f461f63fef5de33c71abd866605a85d2edf3624d7f8f8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8f5860738fd0f3f2568f461f63fef5de33c71abd866605a85d2edf3624d7f8f8", kill_on_drop: false }`
[INFO] [stdout] 8f5860738fd0f3f2568f461f63fef5de33c71abd866605a85d2edf3624d7f8f8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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" "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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 14f6ff429bde808989ee40b228c662417dacb279d2bf0ccebb58de43a7e97be8
[INFO] running `Command { std: "docker" "start" "-a" "14f6ff429bde808989ee40b228c662417dacb279d2bf0ccebb58de43a7e97be8", kill_on_drop: false }`
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling pkg-config v0.3.19
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling unicode-xid v0.2.0
[INFO] [stderr]    Compiling syn v1.0.11
[INFO] [stderr]    Compiling winapi v0.3.8
[INFO] [stderr]    Compiling approx v0.1.1
[INFO] [stderr]    Compiling crc32fast v1.2.0
[INFO] [stderr]    Compiling bitflags v1.2.0
[INFO] [stderr]    Compiling rgb v0.8.14
[INFO] [stderr]    Compiling unicode-segmentation v1.3.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.5
[INFO] [stderr]    Compiling anyhow v1.0.33
[INFO] [stderr]    Compiling vec_map v0.8.1
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling safemem v0.3.3
[INFO] [stderr]    Compiling bytecount v0.6.0
[INFO] [stderr]    Compiling miniz_oxide v0.3.3
[INFO] [stderr]    Compiling rand v0.4.6
[INFO] [stderr]    Compiling num-traits v0.1.43
[INFO] [stderr]    Compiling getrandom v0.1.12
[INFO] [stderr]    Compiling log v0.4.8
[INFO] [stderr]    Compiling thread_local v0.3.6
[INFO] [stderr]    Compiling proc-macro2 v1.0.5
[INFO] [stderr]    Compiling buf_redux v0.8.4
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling regex v1.3.1
[INFO] [stderr]    Compiling c2-chacha v0.2.2
[INFO] [stderr]    Compiling cgmath v0.16.1
[INFO] [stderr]    Compiling quote v1.0.2
[INFO] [stderr]    Compiling rand_pcg v0.2.1
[INFO] [stderr]    Compiling clap v2.33.0
[INFO] [stderr]    Compiling rand_chacha v0.2.1
[INFO] [stderr]    Compiling bzip2-sys v0.1.11+1.0.8
[INFO] [stderr]    Compiling lzma-sys v0.1.17
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling rand v0.7.2
[INFO] [stderr]    Compiling flate2 v1.0.12
[INFO] [stderr]    Compiling xz2 v0.1.6
[INFO] [stderr]    Compiling bzip2 v0.4.3
[INFO] [stderr]    Compiling winconsole v0.10.0
[INFO] [stderr]    Compiling needletail v0.4.1
[INFO] [stderr]    Compiling colored v1.8.0
[INFO] [stderr]    Compiling fern v0.5.9
[INFO] [stderr]    Compiling proc-macro-error v0.2.6
[INFO] [stderr]    Compiling thiserror-impl v1.0.21
[INFO] [stderr]    Compiling structopt-derive v0.3.2
[INFO] [stderr]    Compiling thiserror v1.0.21
[INFO] [stderr]    Compiling structopt v0.3.2
[INFO] [stderr]    Compiling rasusa v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.72s
[INFO] running `Command { std: "docker" "inspect" "14f6ff429bde808989ee40b228c662417dacb279d2bf0ccebb58de43a7e97be8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "14f6ff429bde808989ee40b228c662417dacb279d2bf0ccebb58de43a7e97be8", kill_on_drop: false }`
[INFO] [stdout] 14f6ff429bde808989ee40b228c662417dacb279d2bf0ccebb58de43a7e97be8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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" "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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7cf4df50db0d20ed39ca6dd399e38cf055d02a2b6fc24ec592ff30a0c9f2c14f
[INFO] running `Command { std: "docker" "start" "-a" "7cf4df50db0d20ed39ca6dd399e38cf055d02a2b6fc24ec592ff30a0c9f2c14f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling ryu v1.0.0
[INFO] [stderr]    Compiling itoa v0.4.4
[INFO] [stderr]    Compiling normalize-line-endings v0.2.2
[INFO] [stderr]    Compiling float-cmp v0.4.0
[INFO] [stderr]    Compiling serde_derive v1.0.101
[INFO] [stderr]    Compiling tempfile v3.1.0
[INFO] [stderr]    Compiling predicates v1.0.1
[INFO] [stderr]    Compiling serde v1.0.101
[INFO] [stderr]    Compiling serde_json v1.0.41
[INFO] [stderr]    Compiling escargot v0.3.1
[INFO] [stderr]    Compiling assert_cmd v0.10.2
[INFO] [stderr]    Compiling rasusa v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 22.36s
[INFO] running `Command { std: "docker" "inspect" "7cf4df50db0d20ed39ca6dd399e38cf055d02a2b6fc24ec592ff30a0c9f2c14f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7cf4df50db0d20ed39ca6dd399e38cf055d02a2b6fc24ec592ff30a0c9f2c14f", kill_on_drop: false }`
[INFO] [stdout] 7cf4df50db0d20ed39ca6dd399e38cf055d02a2b6fc24ec592ff30a0c9f2c14f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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" "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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e01aa964743c9fcee2666a7273ff28e2f1c20d9d7d07cfc52adb42c505e8792c
[INFO] running `Command { std: "docker" "start" "-a" "e01aa964743c9fcee2666a7273ff28e2f1c20d9d7d07cfc52adb42c505e8792c", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/rasusa-98e29a8fffc7abb7)
[INFO] [stdout] 
[INFO] [stdout] running 70 tests
[INFO] [stdout] test cli::tests::base_suffix_from_string ... ok
[INFO] [stdout] test cli::tests::divide_u64_by_genome_size ... ok
[INFO] [stdout] test cli::tests::empty_coverage_returns_err ... ok
[INFO] [stdout] test cli::tests::empty_string_is_base_metric_suffix ... ok
[INFO] [stdout] test cli::tests::float_coverage_returns_float ... ok
[INFO] [stdout] test cli::tests::float_and_suffix_returns_multiplied_float_as_int ... ok
[INFO] [stdout] test cli::tests::float_multiply_with_base_unit ... ok
[INFO] [stdout] test cli::tests::invalid_suffix_raises_error ... ok
[INFO] [stdout] test cli::tests::giga_suffix_from_string ... ok
[INFO] [stdout] test cli::tests::kilo_suffix_from_string ... ok
[INFO] [stdout] test cli::tests::no_input_file_given_raises_error ... ok
[INFO] [stdout] test cli::tests::no_genome_size_given_raises_error ... ok
[INFO] [stdout] test cli::tests::invalid_seed_given_raises_error ... ok
[INFO] [stdout] test cli::tests::mega_suffix_from_string ... ok
[INFO] [stdout] test cli::tests::int_and_suffix_returns_multiplied_int ... ok
[INFO] [stdout] test cli::tests::no_args_given_raises_error ... ok
[INFO] [stdout] test cli::tests::no_coverage_given_raises_error ... ok
[INFO] [stdout] test cli::tests::suffix_with_no_size_returns_error ... ok
[INFO] [stdout] test cli::tests::float_only_returns_integer ... ok
[INFO] [stdout] test cli::tests::int_ending_in_x_coverage_returns_float ... ok
[INFO] [stdout] test cli::tests::tera_suffix_from_string ... ok
[INFO] [stdout] test cli::tests::one_input_no_output_is_ok ... ok
[INFO] [stdout] test fastx::tests::create_valid_output_file_and_can_write_to_it ... ok
[INFO] [stdout] test fastx::tests::fastx_from_fasta ... ok
[INFO] [stdout] test cli::tests::zero_coverage_returns_zero ... ok
[INFO] [stdout] test fastx::tests::create_invalid_output_file_raises_error ... ok
[INFO] [stdout] test cli::tests::invalid_coverage_given_raises_error ... ok
[INFO] [stdout] test cli::tests::multiply_coverage_by_genome_size ... ok
[INFO] [stdout] test fastx::tests::filter_fasta_reads_one_index_matches_only_read ... ok
[INFO] [stdout] test fastx::tests::filter_fasta_reads_one_index_out_of_range ... ok
[INFO] [stdout] test fastx::tests::filter_fastq_reads_one_index_out_of_range ... ok
[INFO] [stdout] test cli::tests::integer_only_returns_integer ... ok
[INFO] [stdout] test subsampler::tests::shuffled_indices_for_empty_vector_returns_empty ... ok
[INFO] [stdout] test subsampler::tests::shuffled_indices_for_vector_len_one_returns_zero ... ok
[INFO] [stdout] test subsampler::tests::shuffled_indices_for_vector_len_two_does_shuffle ... ok
[INFO] [stdout] test fastx::tests::filter_fastq_reads_one_index_matches_only_read ... ok
[INFO] [stdout] test fastx::tests::get_read_lengths_for_empty_fasta_returns_empty_vector ... ok
[INFO] [stdout] test fastx::tests::filter_fastq_reads_two_indices_matches_first_and_last_reads ... ok
[INFO] [stdout] test cli::tests::two_input_two_outputs_is_ok ... ok
[INFO] [stdout] test fastx::tests::get_read_lengths_for_fastq ... ok
[INFO] [stdout] test cli::tests::invalid_genome_size_given_raises_error ... ok
[INFO] [stdout] test cli::tests::two_inputs_one_output_raises_error ... ok
[INFO] [stdout] test fastx::tests::get_read_lengths_for_fasta ... ok
[INFO] [stdout] test subsampler::tests::shuffled_indices_with_seed_produces_same_ordering ... ok
[INFO] [stdout] test subsampler::tests::subsample_one_length_greater_than_target_returns_zero ... ok
[INFO] [stdout] test subsampler::tests::subsample_three_lengths_all_greater_than_target_returns_two ... ok
[INFO] [stdout] test subsampler::tests::subsample_three_lengths_sum_greater_than_target_returns_two ... ok
[INFO] [stdout] test subsampler::tests::subsample_one_length_less_than_target_returns_zero ... ok
[INFO] [stdout] test subsampler::tests::subsample_one_length_target_zero_returns_empty ... ok
[INFO] [stdout] test subsampler::tests::subsample_three_lengths_sum_equal_target_returns_three ... ok
[INFO] [stdout] test subsampler::tests::subsample_empty_lengths_returns_empty ... ok
[INFO] [stdout] test subsampler::tests::subsample_three_lengths_sum_less_than_target_returns_three ... ok
[INFO] [stdout] test cli::tests::int_and_base_suffix_returns_int_without_scaling ... ok
[INFO] [stdout] test fastx::tests::create_valid_compressed_output_file_and_can_write_to_it ... ok
[INFO] [stdout] test fastx::tests::filter_fastq_reads_one_index_matches_one_of_two_reads ... ok
[INFO] [stdout] test cli::tests::all_valid_args_with_two_inputs_using_flag_twice_parsed_as_expected ... ok
[INFO] [stdout] test cli::tests::invalid_suffix_returns_err ... ok
[INFO] [stdout] test cli::tests::multiply_genome_size_by_coverage ... ok
[INFO] [stdout] test fastx::tests::filter_reads_empty_indices_no_output ... ok
[INFO] [stdout] test cli::tests::float_without_leading_int_and_suffix_returns_multiplied_float_as_int ... ok
[INFO] [stdout] test cli::tests::non_number_coverage_returns_err ... ok
[INFO] [stdout] test cli::tests::float_ending_in_x_coverage_returns_float ... ok
[INFO] [stdout] test cli::tests::int_coverage_returns_float ... ok
[INFO] [stdout] test cli::tests::empty_string_returns_error ... ok
[INFO] [stdout] test cli::tests::int_and_tera_suffix_returns_multiplied_int ... ok
[INFO] [stdout] test cli::tests::three_inputs_raises_error ... ok
[INFO] [stdout] test cli::tests::all_valid_args_with_two_inputs_parsed_as_expected ... ok
[INFO] [stderr]      Running tests/main.rs (/opt/rustwide/target/debug/deps/main-6758cdab0490de09)
[INFO] [stdout] test cli::tests::one_input_two_outputs_raises_error ... ok
[INFO] [stdout] test cli::tests::all_valid_args_parsed_as_expected ... ok
[INFO] [stdout] test cli::tests::three_outputs_raises_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 70 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.63s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test two_valid_illumina_inputs_suceeds ... ok
[INFO] [stdout] test input_and_output_filetypes_different_raises_no_errors ... ok
[INFO] [stdout] test input_file_doesnt_exist ... ok
[INFO] [stdout] test unequal_number_of_reads_in_inputs_raises_no_errors_but_prints_error_msg ... ok
[INFO] [stdout] test valid_inputs_raises_no_errors ... ok
[INFO] [stdout] test output_file_in_nonexistant_dir ... ok
[INFO] [stdout] test invalid_input_and_output_combination_raises_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 34.26s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e01aa964743c9fcee2666a7273ff28e2f1c20d9d7d07cfc52adb42c505e8792c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e01aa964743c9fcee2666a7273ff28e2f1c20d9d7d07cfc52adb42c505e8792c", kill_on_drop: false }`
[INFO] [stdout] e01aa964743c9fcee2666a7273ff28e2f1c20d9d7d07cfc52adb42c505e8792c