Dec 15 07:16:03.572 INFO testing joinkit-0.2.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 07:16:03.572 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 15 07:16:04.063 INFO [stdout] cf4f86c8b586b2b8afff7ca3fdc9fc79983bff9bf3e564040d45da0f2d7572c3
Dec 15 07:16:04.065 INFO running `"docker" "start" "-a" "cf4f86c8b586b2b8afff7ca3fdc9fc79983bff9bf3e564040d45da0f2d7572c3"`
Dec 15 07:16:05.421 INFO [stderr] usermod: no changes
Dec 15 07:16:05.516 INFO [stderr]    Compiling joinkit v0.2.0 (/source)
Dec 15 07:16:06.883 INFO [stderr] warning: unused `#[macro_use]` import
Dec 15 07:16:06.883 INFO [stderr]   --> src/lib.rs:27:1
Dec 15 07:16:06.883 INFO [stderr]    |
Dec 15 07:16:06.883 INFO [stderr] 27 | #[macro_use]
Dec 15 07:16:06.883 INFO [stderr]    | ^^^^^^^^^^^^
Dec 15 07:16:06.883 INFO [stderr]    |
Dec 15 07:16:06.883 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 15 07:16:06.883 INFO [stderr] 
Dec 15 07:16:07.065 INFO [stderr] warning: unused import: `itertools::Itertools`
Dec 15 07:16:07.065 INFO [stderr]  --> src/util.rs:3:5
Dec 15 07:16:07.065 INFO [stderr]   |
Dec 15 07:16:07.066 INFO [stderr] 3 | use itertools::Itertools;
Dec 15 07:16:07.066 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 15 07:16:07.066 INFO [stderr] 
Dec 15 07:16:07.066 INFO [stderr] warning: unused import: `BufRead`
Dec 15 07:16:07.066 INFO [stderr]  --> src/util.rs:5:15
Dec 15 07:16:07.066 INFO [stderr]   |
Dec 15 07:16:07.066 INFO [stderr] 5 | use std::io::{BufRead, Write, BufWriter,};
Dec 15 07:16:07.066 INFO [stderr]   |               ^^^^^^^
Dec 15 07:16:07.066 INFO [stderr] 
Dec 15 07:16:12.383 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.93s
Dec 15 07:16:12.391 INFO [stderr] su: No module specific data is present
Dec 15 07:16:13.356 INFO running `"docker" "inspect" "cf4f86c8b586b2b8afff7ca3fdc9fc79983bff9bf3e564040d45da0f2d7572c3"`
Dec 15 07:16:13.700 INFO running `"docker" "rm" "-f" "cf4f86c8b586b2b8afff7ca3fdc9fc79983bff9bf3e564040d45da0f2d7572c3"`
Dec 15 07:16:13.986 INFO [stdout] cf4f86c8b586b2b8afff7ca3fdc9fc79983bff9bf3e564040d45da0f2d7572c3
Dec 15 07:16:13.989 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 15 07:16:14.412 INFO [stdout] b564480fef3de92ce51f1421ccd80a511d0ecd7350c72d786e7dcae72b60fd76
Dec 15 07:16:14.414 INFO running `"docker" "start" "-a" "b564480fef3de92ce51f1421ccd80a511d0ecd7350c72d786e7dcae72b60fd76"`
Dec 15 07:16:15.284 INFO [stderr] usermod: no changes
Dec 15 07:16:15.363 INFO [stderr]    Compiling joinkit v0.2.0 (/source)
Dec 15 07:16:21.403 INFO [stderr] warning: unused `#[macro_use]` import
Dec 15 07:16:21.403 INFO [stderr]   --> src/lib.rs:27:1
Dec 15 07:16:21.403 INFO [stderr]    |
Dec 15 07:16:21.403 INFO [stderr] 27 | #[macro_use]
Dec 15 07:16:21.403 INFO [stderr]    | ^^^^^^^^^^^^
Dec 15 07:16:21.415 INFO [stderr]    |
Dec 15 07:16:21.415 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 15 07:16:21.415 INFO [stderr] 
Dec 15 07:16:22.931 INFO [stderr] warning: unused import: `itertools::Itertools`
Dec 15 07:16:22.931 INFO [stderr]  --> src/util.rs:3:5
Dec 15 07:16:22.931 INFO [stderr]   |
Dec 15 07:16:22.931 INFO [stderr] 3 | use itertools::Itertools;
Dec 15 07:16:22.931 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^
Dec 15 07:16:22.931 INFO [stderr] 
Dec 15 07:16:22.931 INFO [stderr] warning: unused import: `BufRead`
Dec 15 07:16:22.931 INFO [stderr]  --> src/util.rs:5:15
Dec 15 07:16:22.931 INFO [stderr]   |
Dec 15 07:16:22.931 INFO [stderr] 5 | use std::io::{BufRead, Write, BufWriter,};
Dec 15 07:16:22.931 INFO [stderr]   |               ^^^^^^^
Dec 15 07:16:22.931 INFO [stderr] 
Dec 15 07:16:25.039 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.73s
Dec 15 07:16:25.039 INFO [stderr] su: No module specific data is present
Dec 15 07:16:26.403 INFO running `"docker" "inspect" "b564480fef3de92ce51f1421ccd80a511d0ecd7350c72d786e7dcae72b60fd76"`
Dec 15 07:16:26.727 INFO running `"docker" "rm" "-f" "b564480fef3de92ce51f1421ccd80a511d0ecd7350c72d786e7dcae72b60fd76"`
Dec 15 07:16:27.087 INFO [stdout] b564480fef3de92ce51f1421ccd80a511d0ecd7350c72d786e7dcae72b60fd76
Dec 15 07:16:27.089 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 15 07:16:27.655 INFO [stdout] 9b095381ed66c913f183fd95856b59a4a74a4356f992f4ee1b2776142df1f3f6
Dec 15 07:16:27.660 INFO running `"docker" "start" "-a" "9b095381ed66c913f183fd95856b59a4a74a4356f992f4ee1b2776142df1f3f6"`
Dec 15 07:16:29.193 INFO [stderr] usermod: no changes
Dec 15 07:16:29.295 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Dec 15 07:16:29.301 INFO [stderr]      Running /target/debug/deps/joinkit-529d7f5907910d71
Dec 15 07:16:29.307 INFO [stdout] 
Dec 15 07:16:29.307 INFO [stdout] running 0 tests
Dec 15 07:16:29.307 INFO [stdout] 
Dec 15 07:16:29.307 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:16:29.307 INFO [stdout] 
Dec 15 07:16:29.307 INFO [stderr]      Running /target/debug/deps/hjoin-d487184cc3427759
Dec 15 07:16:29.319 INFO [stderr]      Running /target/debug/deps/mjoin-45524f901d1aaedc
Dec 15 07:16:29.319 INFO [stderr]      Running /target/debug/deps/hash_join-f50acc6c234b2647
Dec 15 07:16:29.319 INFO [stderr]      Running /target/debug/deps/merge_join-830d35fd95e4063f
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] running 0 tests
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] running 0 tests
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] running 12 tests
Dec 15 07:16:29.319 INFO [stdout] test left_outer_fused_inv ... ok
Dec 15 07:16:29.319 INFO [stdout] test left_outer_fused ... ok
Dec 15 07:16:29.319 INFO [stdout] test left_excl_fused_inv ... ok
Dec 15 07:16:29.319 INFO [stdout] test left_excl_fused ... ok
Dec 15 07:16:29.319 INFO [stdout] test inner_fused_inv ... ok
Dec 15 07:16:29.319 INFO [stdout] test full_outer_fused ... ok
Dec 15 07:16:29.319 INFO [stdout] test right_excl_fused_inv ... ok
Dec 15 07:16:29.319 INFO [stdout] test right_outer_fused ... ok
Dec 15 07:16:29.319 INFO [stdout] test right_outer_fused_inv ... ok
Dec 15 07:16:29.319 INFO [stdout] test right_excl_fused ... ok
Dec 15 07:16:29.319 INFO [stdout] test full_outer_fused_inv ... ok
Dec 15 07:16:29.319 INFO [stdout] test inner_fused ... ok
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.319 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:16:29.319 INFO [stdout] 
Dec 15 07:16:29.323 INFO [stdout] 
Dec 15 07:16:29.323 INFO [stdout] running 8 tests
Dec 15 07:16:29.323 INFO [stdout] test left_excl_fused ... ok
Dec 15 07:16:29.323 INFO [stdout] test full_outer_fused_inv ... ok
Dec 15 07:16:29.323 INFO [stdout] test inner_fused ... ok
Dec 15 07:16:29.323 INFO [stdout] test inner_fused_inv ... ok
Dec 15 07:16:29.331 INFO [stdout] test left_excl_fused_inv ... ok
Dec 15 07:16:29.331 INFO [stdout] test left_outer_fused ... ok
Dec 15 07:16:29.331 INFO [stdout] test left_outer_fused_inv ... ok
Dec 15 07:16:29.331 INFO [stdout] test full_outer_fused ... ok
Dec 15 07:16:29.331 INFO [stdout] 
Dec 15 07:16:29.331 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:16:29.331 INFO [stdout] 
Dec 15 07:16:29.339 INFO [stderr]      Running /target/debug/deps/util-a73926138f0d715b
Dec 15 07:16:29.347 INFO [stderr]    Doc-tests joinkit
Dec 15 07:16:29.347 INFO [stdout] 
Dec 15 07:16:29.347 INFO [stdout] running 4 tests
Dec 15 07:16:29.347 INFO [stdout] test extract_key_multiple_eq ... ok
Dec 15 07:16:29.347 INFO [stdout] test extract_key_single_eq ... ok
Dec 15 07:16:29.347 INFO [stdout] test extract_key_multiple_ne ... ok
Dec 15 07:16:29.347 INFO [stdout] test extract_key_single_ne ... ok
Dec 15 07:16:29.347 INFO [stdout] 
Dec 15 07:16:29.347 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:16:29.347 INFO [stdout] 
Dec 15 07:16:31.475 INFO [stdout] 
Dec 15 07:16:31.475 INFO [stdout] running 15 tests
Dec 15 07:16:40.403 INFO [stdout] test src/lib.rs -  (line 20) ... ok
Dec 15 07:16:42.639 INFO [stdout] test src/lib.rs - Joinkit::hash_join_inner (line 209) ... ok
Dec 15 07:16:46.831 INFO [stdout] test src/lib.rs - Joinkit::hash_join_full_outer (line 403) ... ok
Dec 15 07:16:47.736 INFO [stdout] test src/lib.rs - Joinkit::hash_join_right_outer (line 361) ... ok
Dec 15 07:16:48.903 INFO [stdout] test src/lib.rs - Joinkit::merge_join_full_outer_by (line 165) ... ok
Dec 15 07:16:49.640 INFO [stdout] test src/lib.rs - Joinkit::hash_join_left_outer (line 284) ... ok
Dec 15 07:16:49.675 INFO [stdout] test src/lib.rs - Joinkit::hash_join_left_excl (line 245) ... ok
Dec 15 07:16:50.698 INFO [stdout] test src/lib.rs - Joinkit::hash_join_right_excl (line 322) ... ok
Dec 15 07:16:57.839 INFO [stdout] test src/lib.rs - Joinkit::merge_join_inner_by (line 68) ... ok
Dec 15 07:16:59.092 INFO [stdout] test src/lib.rs - Joinkit::merge_join_left_outer_by (line 131) ... ok
Dec 15 07:16:59.224 INFO [stdout] test src/lib.rs - Joinkit::merge_join_left_excl_by (line 99) ... ok
Dec 15 07:17:00.627 INFO [stdout] test src/util.rs - util::extract_key_value (line 208) ... ok
Dec 15 07:17:00.943 INFO [stdout] test src/util.rs - util::extract_key (line 143) ... ok
Dec 15 07:17:01.239 INFO [stdout] test src/util.rs - util::fields_to_idx (line 58) ... ok
Dec 15 07:17:01.472 INFO [stdout] test src/util.rs - util::num_fields (line 236) ... ok
Dec 15 07:17:01.472 INFO [stdout] 
Dec 15 07:17:01.472 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 07:17:01.472 INFO [stdout] 
Dec 15 07:17:01.511 INFO [stderr] su: No module specific data is present
Dec 15 07:17:02.497 INFO running `"docker" "inspect" "9b095381ed66c913f183fd95856b59a4a74a4356f992f4ee1b2776142df1f3f6"`
Dec 15 07:17:02.701 INFO running `"docker" "rm" "-f" "9b095381ed66c913f183fd95856b59a4a74a4356f992f4ee1b2776142df1f3f6"`
Dec 15 07:17:02.906 INFO [stdout] 9b095381ed66c913f183fd95856b59a4a74a4356f992f4ee1b2776142df1f3f6