[INFO] cloning repository https://github.com/alejandrogzi/psltools
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/alejandrogzi/psltools" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Falejandrogzi%2Fpsltools", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Falejandrogzi%2Fpsltools'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] c096188657530815a602dfef49fb57dc0d01f92a
[INFO] testing alejandrogzi/psltools against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Falejandrogzi%2Fpsltools" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/alejandrogzi/psltools
[INFO] finished tweaking git repo https://github.com/alejandrogzi/psltools
[INFO] tweaked toml for git repo https://github.com/alejandrogzi/psltools written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/alejandrogzi/psltools 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 git repo https://github.com/alejandrogzi/psltools 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 resvg v0.45.1
[INFO] [stderr]   Downloaded simple_logger v5.2.0
[INFO] [stderr]   Downloaded svgtypes v0.15.3
[INFO] [stderr]   Downloaded zune-jpeg v0.4.21
[INFO] [stderr]   Downloaded imagesize v0.13.0
[INFO] [stderr]   Downloaded memmap2 v0.9.10
[INFO] [stderr]   Downloaded genepred v0.0.16
[INFO] [stderr]   Downloaded usvg v0.45.1
[INFO] [stderr]   Downloaded kurbo v0.11.3
[INFO] [stderr]   Downloaded zlib-rs v0.6.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208
[INFO] running `Command { std: "docker" "start" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", 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" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208" "/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" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", 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" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling num-conv v0.2.2
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling log v0.4.32
[INFO] [stderr]    Compiling either v1.16.0
[INFO] [stderr]    Compiling memchr v2.8.1
[INFO] [stderr]    Compiling time-macros v0.2.27
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling genepred v0.0.16
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling memmap2 v0.9.10
[INFO] [stderr]    Compiling simple_logger v5.2.0
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling psltools v0.0.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 27.52s
[INFO] running `Command { std: "docker" "inspect" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", 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" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208" "/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 serde_core v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling psltools v0.0.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 10.92s
[INFO] running `Command { std: "docker" "inspect" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", 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" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208" "/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.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/psltools-277b368f9d3f0592)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests bench/benchmark.rs (/opt/rustwide/target/debug/deps/benchmark-523b3f67c9fcf189)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/psltools-bbba146b8877a780)
[INFO] [stdout] 
[INFO] [stdout] running 18 tests
[INFO] [stdout] test cli::dotplot::tests::chromosome_offsets_use_natural_order ... ok
[INFO] [stdout] test cli::dotplot::tests::binned_svg_emits_rects ... ok
[INFO] [stdout] test cli::dotplot::tests::dot_table_flips_strands_keeps_gaps_and_offsets ... ok
[INFO] [stdout] test cli::dotplot::tests::filters_query_reference_strand_and_sizes ... ok
[INFO] [stdout] test cli::dotplot::tests::infers_format_from_extension ... ok
[INFO] [stdout] test cli::dotplot::tests::min_block_size_drops_short_blocks ... ok
[INFO] [stdout] test cli::dotplot::tests::minus_strand_is_backslash ... ok
[INFO] [stdout] test cli::dotplot::tests::empty_input_is_valid_svg ... ok
[INFO] [stdout] test cli::dotplot::tests::multi_block_keeps_gaps ... ok
[INFO] [stdout] test cli::dotplot::tests::png_without_feature_errors ... ok
[INFO] [stdout] test cli::dotplot::tests::plus_strand_is_slash ... ok
[INFO] [stdout] test cli::dotplot::tests::protein_plus_uses_size_mul ... ok
[INFO] [stdout] test cli::tests::honors_requested_level ... ok
[INFO] [stdout] test cli::dotplot::tests::rejects_missing_query_and_zero_bins ... ok
[INFO] [stdout] test cli::tests::defaults_to_info_when_unspecified ... ok
[INFO] [stdout] test cli::dotplot::tests::svg_smoke_file_and_stdin ... ok
[INFO] [stdout] test cli::tests::parses_globals_before_and_after_subcommand ... ok
[INFO] [stdout] test cli::dotplot::tests::protein_minus_reference_flips_x ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/golden.rs (/opt/rustwide/target/debug/deps/golden-31f0cef04feb01f4)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test perfect_alignment_is_zero_milli_bad ... ok
[INFO] [stdout] test score_protein_uses_size_mul_3 ... ok
[INFO] [stdout] test score_simple_dna ... ok
[INFO] [stdout] test score_with_mismatches_and_perfect_identity_block ... ok
[INFO] [stderr]      Running tests/index.rs (/opt/rustwide/target/debug/deps/index-554eabb3edf1c5f7)
[INFO] [stdout] test milli_bad_exercises_log_term ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/io.rs (/opt/rustwide/target/debug/deps/io-af3d749f94e998a6)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test options_default_mrna_false ... ok
[INFO] [stdout] test parallel_matches_sequential ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ops.rs (/opt/rustwide/target/debug/deps/ops-a92b58bc9adf7bb1)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test check_flags_span_inconsistency ... ok
[INFO] [stdout] test swap_is_involution_for_translated ... ok
[INFO] [stdout] test swap_is_involution_for_plus_strand ... ok
[INFO] [stdout] test check_passes_on_valid_records ... ok
[INFO] [stdout] test swap_is_involution_for_minus_strand ... ok
[INFO] [stdout] test to_bed12_basic ... ok
[INFO] [stdout] test swap_exchanges_query_and_reference ... ok
[INFO] [stdout] test to_bed6_basic ... ok
[INFO] [stdout] test to_bed12_multiblock_uses_reference_intervals ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/parsing.rs (/opt/rustwide/target/debug/deps/parsing-715be298269b2bbc)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test parses_translated_two_char_strand ... ok
[INFO] [stdout] test handles_crlf_line_endings ... ok
[INFO] [stdout] test rejects_blockcount_mismatch ... ok
[INFO] [stdout] test rejects_bad_coordinate ... ok
[INFO] [stdout] test rejects_invalid_strand ... ok
[INFO] [stdout] test empty_input_yields_no_records ... ok
[INFO] [stdout] test rejects_missing_columns ... ok
[INFO] [stdout] test parses_basic_records ... ok
[INFO] [stdout] test rejects_non_digit_field ... ok
[INFO] [stderr]      Running tests/property.rs (/opt/rustwide/target/debug/deps/property-013d7a1102754289)
[INFO] [stdout] test skips_blank_and_browser_lines ... ok
[INFO] [stdout] test skips_pslayout_header_and_track_lines ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test generated_records_check_roundtrip_and_swap ... ok
[INFO] [stderr]      Running tests/roundtrip.rs (/opt/rustwide/target/debug/deps/roundtrip-b9c5a7778464e033)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test roundtrip_translated ... ok
[INFO] [stdout] test roundtrip_pslx ... ok
[INFO] [stdout] test roundtrip_neg_strand ... ok
[INFO] [stdout] test writer_emits_canonical_tabs_and_trailing_commas ... ok
[INFO] [stdout] test roundtrip_with_header ... ok
[INFO] [stdout] test roundtrip_basic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/storage.rs (/opt/rustwide/target/debug/deps/storage-1b673f5e60bc05ca)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test byte_slice_views_and_utf8 ... ok
[INFO] [stdout] test byte_slice_invalid_utf8_is_none ... ok
[INFO] [stdout] test cloning_byte_slice_is_cheap_and_consistent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/strands.rs (/opt/rustwide/target/debug/deps/strands-02ca25285df6e1ea)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test plain_dna_is_not_protein ... ok
[INFO] [stdout] test overlaps_reference_region ... ok
[INFO] [stdout] test positive_strand_query_block_forward_is_identity ... ok
[INFO] [stdout] test negative_strand_query_block_forward_intervals ... ok
[INFO] [stderr]      Running tests/streaming.rs (/opt/rustwide/target/debug/deps/streaming-a6465e7841bf12b2)
[INFO] [stdout] test translated_protein_is_detected_and_uses_size_mul_3 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test streams_records ... ok
[INFO] [stdout] test skips_header_and_blank_lines ... ok
[INFO] [stdout] test header_first_filtering_skips_blocks ... ok
[INFO] [stdout] test streams_pslx_sequences ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests psltools
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/lib.rs - (line 26) - compile ... ok
[INFO] [stdout] test src/model/error.rs - model::error::PslError (line 20) ... ok
[INFO] [stdout] test src/io/storage.rs - io::storage::ByteSlice (line 59) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.62s; merged doctests compilation took 0.61s
[INFO] running `Command { std: "docker" "inspect" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208", kill_on_drop: false }`
[INFO] [stdout] 262fe4d95b2505a6c0fbe857574175c7605b0331c264fbc4ed6324d95bf0a208
