Dec 13 00:35:51.858 INFO testing tfursten/mtsv_tools against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 00:35:51.858 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 00:35:52.312 INFO [stdout] 7c163678cd5d0829a3765094fa670236c22c1d2036b85018781896a4ae260db6
Dec 13 00:35:52.321 INFO running `"docker" "start" "-a" "7c163678cd5d0829a3765094fa670236c22c1d2036b85018781896a4ae260db6"`
Dec 13 00:35:53.282 INFO [stderr] usermod: no changes
Dec 13 00:35:53.532 INFO [stderr]    Compiling num-traits v0.2.0
Dec 13 00:35:53.532 INFO [stderr]    Compiling fixedbitset v0.1.8
Dec 13 00:35:53.541 INFO [stderr]    Compiling itertools v0.4.19
Dec 13 00:35:53.541 INFO [stderr]    Compiling rand v0.4.2
Dec 13 00:35:53.541 INFO [stderr]    Compiling memchr v0.1.11
Dec 13 00:35:53.541 INFO [stderr]    Compiling xattr v0.1.11
Dec 13 00:35:53.541 INFO [stderr]    Compiling miniz-sys v0.1.10
Dec 13 00:35:53.541 INFO [stderr]    Compiling ssw v0.1.0 (/source/ssw)
Dec 13 00:35:56.841 INFO [stderr]    Compiling filetime v0.1.15
Dec 13 00:35:56.987 INFO [stderr]    Compiling bit-set v0.3.0
Dec 13 00:35:57.019 INFO [stderr]    Compiling csv v0.14.7
Dec 13 00:35:58.219 INFO [stderr]    Compiling aho-corasick v0.5.3
Dec 13 00:35:58.269 INFO [stderr]    Compiling petgraph v0.2.7 (/source/vendor/petgraph)
Dec 13 00:36:00.044 INFO [stderr]    Compiling clap v2.29.4
Dec 13 00:36:05.235 INFO [stderr]    Compiling syncbox v0.2.4
Dec 13 00:36:05.236 INFO [stderr]    Compiling tar v0.4.14
Dec 13 00:36:05.296 INFO [stderr]    Compiling num-integer v0.1.36
Dec 13 00:36:05.593 INFO [stderr]    Compiling num-traits v0.1.43
Dec 13 00:36:06.267 INFO [stderr]    Compiling num-complex v0.1.42
Dec 13 00:36:08.055 INFO [stderr]    Compiling thread-id v2.0.0
Dec 13 00:36:09.300 INFO [stderr]    Compiling bincode v0.5.9
Dec 13 00:36:10.157 INFO [stderr]    Compiling flate2 v0.2.20
Dec 13 00:36:13.092 INFO [stderr]    Compiling num-iter v0.1.35
Dec 13 00:36:13.248 INFO [stderr]    Compiling thread_local v0.2.7
Dec 13 00:36:13.403 INFO [stderr]    Compiling daggy v0.4.0 (/source/vendor/daggy)
Dec 13 00:36:18.000 INFO [stderr]    Compiling cue v0.1.0
Dec 13 00:36:18.912 INFO [stderr]    Compiling num-bigint v0.1.42
Dec 13 00:36:18.914 INFO [stderr]    Compiling regex v0.1.80
Dec 13 00:36:25.800 INFO [stderr]    Compiling num-rational v0.1.41
Dec 13 00:36:37.203 INFO [stderr]    Compiling num v0.1.41
Dec 13 00:36:37.437 INFO [stderr]    Compiling nalgebra v0.4.0
Dec 13 00:36:37.437 INFO [stderr]    Compiling stopwatch v0.0.6
Dec 13 00:36:38.151 INFO [stderr]    Compiling chrono v0.2.25
Dec 13 00:36:48.631 INFO [stderr]    Compiling env_logger v0.3.5
Dec 13 00:36:59.671 INFO [stderr]    Compiling bio v0.5.0 (/source/vendor/rust-bio)
Dec 13 00:37:05.223 INFO [stderr]    Compiling mtsv v0.4.0 (/source)
Dec 13 00:37:35.497 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 42s
Dec 13 00:37:35.510 INFO [stderr] su: No module specific data is present
Dec 13 00:37:39.280 INFO running `"docker" "inspect" "7c163678cd5d0829a3765094fa670236c22c1d2036b85018781896a4ae260db6"`
Dec 13 00:37:39.607 INFO running `"docker" "rm" "-f" "7c163678cd5d0829a3765094fa670236c22c1d2036b85018781896a4ae260db6"`
Dec 13 00:37:39.911 INFO [stdout] 7c163678cd5d0829a3765094fa670236c22c1d2036b85018781896a4ae260db6
Dec 13 00:37:39.919 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 00:37:40.629 INFO [stdout] 67277919185bec337cc7e9e240d4604cf59990494b872e5377e93d1ce2c338b3
Dec 13 00:37:40.631 INFO running `"docker" "start" "-a" "67277919185bec337cc7e9e240d4604cf59990494b872e5377e93d1ce2c338b3"`
Dec 13 00:37:43.406 INFO [stderr] usermod: no changes
Dec 13 00:37:43.656 INFO [stderr]    Compiling rand v0.3.22
Dec 13 00:37:44.009 INFO [stderr]    Compiling uuid v0.1.18
Dec 13 00:37:44.011 INFO [stderr]    Compiling quickcheck v0.3.2
Dec 13 00:37:45.041 INFO [stderr]    Compiling mktemp v0.2.1
Dec 13 00:37:47.747 INFO [stderr]    Compiling mtsv v0.4.0 (/source)
Dec 13 00:38:06.180 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.75s
Dec 13 00:38:06.203 INFO [stderr] su: No module specific data is present
Dec 13 00:38:07.439 INFO running `"docker" "inspect" "67277919185bec337cc7e9e240d4604cf59990494b872e5377e93d1ce2c338b3"`
Dec 13 00:38:07.780 INFO running `"docker" "rm" "-f" "67277919185bec337cc7e9e240d4604cf59990494b872e5377e93d1ce2c338b3"`
Dec 13 00:38:08.087 INFO [stdout] 67277919185bec337cc7e9e240d4604cf59990494b872e5377e93d1ce2c338b3
Dec 13 00:38:08.089 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 00:38:08.579 INFO [stdout] a2128966065ae11218d886d183d01c2ab076d14a7358978b4ace21d3aa8323b9
Dec 13 00:38:08.583 INFO running `"docker" "start" "-a" "a2128966065ae11218d886d183d01c2ab076d14a7358978b4ace21d3aa8323b9"`
Dec 13 00:38:10.298 INFO [stderr] usermod: no changes
Dec 13 00:38:10.567 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Dec 13 00:38:10.579 INFO [stderr]      Running /target/debug/deps/mtsv-6803f914dc91b737
Dec 13 00:38:10.584 INFO [stdout] 
Dec 13 00:38:10.584 INFO [stdout] running 71 tests
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_exact_full ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_empty ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_begin_edits ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_end_edits ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_from_jupyter_notebook ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_middle_edits ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_exact_partial ... ok
Dec 13 00:38:10.603 INFO [stdout] test binner::test::success_empty ... ok
Dec 13 00:38:10.603 INFO [stdout] test binner::test::success_many ... ok
Dec 13 00:38:10.603 INFO [stdout] test binner::test::success_single_spaces ... ok
Dec 13 00:38:10.603 INFO [stdout] test align::test::test_nomatches ... ok
Dec 13 00:38:10.619 INFO [stdout] test collapse::test::simple_collapse ... ok
Dec 13 00:38:10.763 INFO [stdout] test builder::test::success ... ok
Dec 13 00:38:10.763 INFO [stdout] test index::test::seed_hits_success ... ok
Dec 13 00:38:11.234 INFO [stdout] test builder::test::fail_empty_header ... ok
Dec 13 00:38:11.245 INFO [stdout] test index::test::seed_hits_fail ... ok
Dec 13 00:38:11.249 INFO [stdout] test index::test::reference_candidate_different_bin ... ok
Dec 13 00:38:11.249 INFO [stdout] test io::test::invalid_ids ... ok
Dec 13 00:38:11.250 INFO [stdout] test io::test::parsing_positive ... ok
Dec 13 00:38:11.251 INFO [stdout] test io::test::no_read_header ... ok
Dec 13 00:38:11.251 INFO [stdout] test io::test::missing_ids ... ok
Dec 13 00:38:11.252 INFO [stdout] test io::test::roundtrip_single ... ok
Dec 13 00:38:11.255 INFO [stdout] test prep::tests::adapters_empty ... ok
Dec 13 00:38:11.256 INFO [stdout] test prep::tests::adapters_present_and_above_tolerance ... ok
Dec 13 00:38:11.256 INFO [stdout] test prep::tests::adapters_present_but_below_tolerance ... ok
Dec 13 00:38:11.257 INFO [stdout] test prep::tests::adapters_whole_sequence ... ok
Dec 13 00:38:11.257 INFO [stdout] test prep::tests::highest_at_end ... ok
Dec 13 00:38:11.257 INFO [stdout] test prep::tests::highest_at_start ... ok
Dec 13 00:38:11.263 INFO [stdout] test prep::tests::highest_in_middle ... ok
Dec 13 00:38:11.263 INFO [stdout] test prep::tests::highest_q_same_length ... ok
Dec 13 00:38:11.263 INFO [stdout] test prep::tests::lcd_trim_diff_length ... ok
Dec 13 00:38:11.263 INFO [stdout] test prep::tests::lcd_trim_same_length ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::lcdq_trim_diff_length ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::lcdq_trim_same_length ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::quality_filter ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::adapters_none ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::segment_trim_same_len ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::segment_trim_even_division ... ok
Dec 13 00:38:11.264 INFO [stdout] test prep::tests::segment_trim_uneven_division ... ok
Dec 13 00:38:11.265 INFO [stdout] test prep::tests::test_highest_q_longer_desired_than_avail ... ok
Dec 13 00:38:11.265 INFO [stdout] test prep::tests::test_highest_q_zero_goal ... ok
Dec 13 00:38:12.715 INFO [stdout] test index::test::reference_candidate_non_overlapping ... ok
Dec 13 00:38:15.110 INFO [stdout] test io::test::io_helpers ... ok
Dec 13 00:38:15.110 INFO [stdout] test prep_config::test::test_subadapters ... ok
Dec 13 00:38:15.123 INFO [stdout] test tax_tree::test::human_ancestors ... ok
Dec 13 00:38:15.127 INFO [stdout] test tax_tree::test::human_siblings_lca ... ok
Dec 13 00:38:15.134 INFO [stdout] test tax_tree::test::human_siblings_rank ... ok
Dec 13 00:38:15.159 INFO [stdout] test tax_tree::test::informative_parent ... ok
Dec 13 00:38:15.159 INFO [stdout] test tax_tree::test::tokenize ... ok
Dec 13 00:38:15.159 INFO [stdout] test tax_tree::test::tokenize_empty ... ok
Dec 13 00:38:15.159 INFO [stdout] test tax_tree::test::tokenize_fail_parent_bad ... ok
Dec 13 00:38:15.167 INFO [stdout] test tax_tree::test::tokenize_fail_parent_missing ... ok
Dec 13 00:38:15.167 INFO [stdout] test tax_tree::test::tokenize_fail_rank_missing ... ok
Dec 13 00:38:15.167 INFO [stdout] test tax_tree::test::tokenize_fail_taxid_bad ... ok
Dec 13 00:38:15.167 INFO [stdout] test tax_tree::test::tokenize_fail_taxid_missing ... ok
Dec 13 00:38:15.175 INFO [stdout] test util::test::fail_decimal_gi ... ok
Dec 13 00:38:15.191 INFO [stdout] test util::test::fail_decimal_taxid ... ok
Dec 13 00:38:15.191 INFO [stdout] test util::test::fail_empty ... ok
Dec 13 00:38:15.198 INFO [stdout] test util::test::fail_empty_nodash ... ok
Dec 13 00:38:15.198 INFO [stdout] test util::test::fail_extra ... ok
Dec 13 00:38:15.198 INFO [stdout] test util::test::fail_non_numeric_gi ... ok
Dec 13 00:38:15.198 INFO [stdout] test util::test::fail_non_numeric_taxid ... ok
Dec 13 00:38:15.211 INFO [stdout] test util::test::lines_for_the_line_throne ... ok
Dec 13 00:38:15.215 INFO [stdout] test util::test::success ... ok
Dec 13 00:38:18.252 INFO [stdout] test prep_config::test::segment ... ok
Dec 13 00:38:20.095 INFO [stdout] test prep_config::test::lcd ... ok
Dec 13 00:38:45.507 INFO [stdout] test io::test::roundtrip_many ... ok
Dec 13 00:39:10.599 INFO [stdout] test index::test::construct_index_lowercase ... test index::test::construct_index_lowercase has been running for over 60 seconds
Dec 13 00:39:10.599 INFO [stdout] test chunk::test::chunk_roundtrip ... test chunk::test::chunk_roundtrip has been running for over 60 seconds
Dec 13 00:39:10.599 INFO [stdout] test index::test::reference_candidate ... test index::test::reference_candidate has been running for over 60 seconds
Dec 13 00:39:11.275 INFO [stdout] test prep::tests::test_prep_integration ... test prep::tests::test_prep_integration has been running for over 60 seconds
Dec 13 00:39:23.763 INFO [stdout] test prep::tests::test_prep_integration ... ok
Dec 13 00:39:43.251 INFO [stdout] test index::test::construct_index_lowercase ... ok
Dec 13 00:39:56.599 INFO [stdout] test chunk::test::chunk_roundtrip ... ok
Dec 13 00:40:57.858 INFO [stdout] test index::test::reference_candidate ... ok
Dec 13 00:40:57.858 INFO [stdout] 
Dec 13 00:40:57.858 INFO [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.858 INFO [stdout] 
Dec 13 00:40:57.871 INFO [stderr]      Running /target/debug/deps/mtsv_binner-54bdb1e31d8078b5
Dec 13 00:40:57.897 INFO [stdout] 
Dec 13 00:40:57.897 INFO [stdout] running 0 tests
Dec 13 00:40:57.897 INFO [stdout] 
Dec 13 00:40:57.898 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.898 INFO [stdout] 
Dec 13 00:40:57.899 INFO [stderr]      Running /target/debug/deps/mtsv_build-ccc153f15d5afd65
Dec 13 00:40:57.905 INFO [stdout] 
Dec 13 00:40:57.905 INFO [stdout] running 0 tests
Dec 13 00:40:57.905 INFO [stdout] 
Dec 13 00:40:57.905 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.905 INFO [stdout] 
Dec 13 00:40:57.907 INFO [stderr]      Running /target/debug/deps/mtsv_chunk-06c1cd38187f3133
Dec 13 00:40:57.927 INFO [stdout] 
Dec 13 00:40:57.927 INFO [stdout] running 0 tests
Dec 13 00:40:57.927 INFO [stdout] 
Dec 13 00:40:57.927 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.927 INFO [stdout] 
Dec 13 00:40:57.927 INFO [stderr]      Running /target/debug/deps/mtsv_collapse-471a00c667220c9b
Dec 13 00:40:57.933 INFO [stdout] 
Dec 13 00:40:57.933 INFO [stdout] running 0 tests
Dec 13 00:40:57.933 INFO [stdout] 
Dec 13 00:40:57.933 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.933 INFO [stdout] 
Dec 13 00:40:57.939 INFO [stderr]      Running /target/debug/deps/mtsv_readprep-8747c112a9a19716
Dec 13 00:40:57.967 INFO [stdout] 
Dec 13 00:40:57.967 INFO [stdout] running 0 tests
Dec 13 00:40:57.967 INFO [stdout] 
Dec 13 00:40:57.967 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.967 INFO [stdout] 
Dec 13 00:40:57.967 INFO [stderr]      Running /target/debug/deps/mtsv_signature-7cd1125a2e0f8ac9
Dec 13 00:40:57.995 INFO [stdout] 
Dec 13 00:40:57.995 INFO [stdout] running 0 tests
Dec 13 00:40:57.995 INFO [stdout] 
Dec 13 00:40:57.995 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:57.995 INFO [stdout] 
Dec 13 00:40:57.995 INFO [stderr]      Running /target/debug/deps/mtsv_tree_build-2c329cd7bafc36c8
Dec 13 00:40:58.006 INFO [stdout] 
Dec 13 00:40:58.006 INFO [stdout] running 0 tests
Dec 13 00:40:58.006 INFO [stdout] 
Dec 13 00:40:58.006 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:58.006 INFO [stdout] 
Dec 13 00:40:58.006 INFO [stderr]    Doc-tests mtsv
Dec 13 00:40:59.507 INFO [stdout] 
Dec 13 00:40:59.507 INFO [stdout] running 0 tests
Dec 13 00:40:59.507 INFO [stdout] 
Dec 13 00:40:59.507 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 00:40:59.507 INFO [stdout] 
Dec 13 00:40:59.527 INFO [stderr] su: No module specific data is present
Dec 13 00:41:01.489 INFO running `"docker" "inspect" "a2128966065ae11218d886d183d01c2ab076d14a7358978b4ace21d3aa8323b9"`
Dec 13 00:41:01.879 INFO running `"docker" "rm" "-f" "a2128966065ae11218d886d183d01c2ab076d14a7358978b4ace21d3aa8323b9"`
Dec 13 00:41:02.295 INFO [stdout] a2128966065ae11218d886d183d01c2ab076d14a7358978b4ace21d3aa8323b9