Dec 14 19:58:03.141 INFO testing shine-graph-0.1.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 19:58:03.141 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 14 19:58:03.612 INFO [stdout] 821ed9084eba20056970713a3cd1d9c2da983c734bc87aebba83b7f7c602c4f9
Dec 14 19:58:03.614 INFO running `"docker" "start" "-a" "821ed9084eba20056970713a3cd1d9c2da983c734bc87aebba83b7f7c602c4f9"`
Dec 14 19:58:05.653 INFO [stderr] usermod: no changes
Dec 14 19:58:05.838 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Dec 14 19:58:05.851 INFO [stderr]    Compiling shine-store v0.2.0
Dec 14 19:58:05.851 INFO [stderr]    Compiling shine-graph-macro v0.1.1
Dec 14 19:58:13.523 INFO [stderr] warning: the feature `tool_lints` has been stable since 1.31.0 and no longer requires an attribute to enable
Dec 14 19:58:13.523 INFO [stderr]  --> src/lib.rs:5:12
Dec 14 19:58:13.523 INFO [stderr]   |
Dec 14 19:58:13.523 INFO [stderr] 5 | #![feature(tool_lints)]
Dec 14 19:58:13.523 INFO [stderr]   |            ^^^^^^^^^^
Dec 14 19:58:13.523 INFO [stderr]   |
Dec 14 19:58:13.523 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 14 19:58:13.523 INFO [stderr] 
Dec 14 19:58:13.531 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 14 19:58:13.531 INFO [stderr]    --> src/svec/svector.rs:176:9
Dec 14 19:58:13.531 INFO [stderr]     |
Dec 14 19:58:13.531 INFO [stderr] 176 |         write!(f, "[");
Dec 14 19:58:13.531 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 14 19:58:13.531 INFO [stderr]     |
Dec 14 19:58:13.531 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 14 19:58:13.531 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 14 19:58:13.531 INFO [stderr]     = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Dec 14 19:58:13.531 INFO [stderr] 
Dec 14 19:58:13.531 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 14 19:58:13.531 INFO [stderr]    --> src/svec/svector.rs:181:9
Dec 14 19:58:13.531 INFO [stderr]     |
Dec 14 19:58:13.531 INFO [stderr] 181 |         write!(f, "]");
Dec 14 19:58:13.531 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 14 19:58:13.531 INFO [stderr]     |
Dec 14 19:58:13.531 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 14 19:58:13.531 INFO [stderr]     = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Dec 14 19:58:13.531 INFO [stderr] 
Dec 14 19:58:14.083 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.40s
Dec 14 19:58:14.091 INFO [stderr] su: No module specific data is present
Dec 14 19:58:14.843 INFO running `"docker" "inspect" "821ed9084eba20056970713a3cd1d9c2da983c734bc87aebba83b7f7c602c4f9"`
Dec 14 19:58:15.117 INFO running `"docker" "rm" "-f" "821ed9084eba20056970713a3cd1d9c2da983c734bc87aebba83b7f7c602c4f9"`
Dec 14 19:58:15.281 INFO [stdout] 821ed9084eba20056970713a3cd1d9c2da983c734bc87aebba83b7f7c602c4f9
Dec 14 19:58:15.285 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 14 19:58:15.743 INFO [stdout] ce145a0ba98296831360fafab08a192fa7917958e19946cd05c7966f0d0beebe
Dec 14 19:58:15.748 INFO running `"docker" "start" "-a" "ce145a0ba98296831360fafab08a192fa7917958e19946cd05c7966f0d0beebe"`
Dec 14 19:58:17.046 INFO [stderr] usermod: no changes
Dec 14 19:58:17.175 INFO [stderr]    Compiling permutohedron v0.2.4
Dec 14 19:58:17.177 INFO [stderr]    Compiling shine-testutils v0.1.0
Dec 14 19:58:19.379 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Dec 14 19:58:27.055 INFO [stderr] warning: the feature `tool_lints` has been stable since 1.31.0 and no longer requires an attribute to enable
Dec 14 19:58:27.055 INFO [stderr]  --> src/lib.rs:5:12
Dec 14 19:58:27.055 INFO [stderr]   |
Dec 14 19:58:27.055 INFO [stderr] 5 | #![feature(tool_lints)]
Dec 14 19:58:27.055 INFO [stderr]   |            ^^^^^^^^^^
Dec 14 19:58:27.055 INFO [stderr]   |
Dec 14 19:58:27.055 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 14 19:58:27.055 INFO [stderr] 
Dec 14 19:58:27.056 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 14 19:58:27.056 INFO [stderr]    --> src/svec/svector.rs:176:9
Dec 14 19:58:27.056 INFO [stderr]     |
Dec 14 19:58:27.056 INFO [stderr] 176 |         write!(f, "[");
Dec 14 19:58:27.056 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 14 19:58:27.056 INFO [stderr]     |
Dec 14 19:58:27.056 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 14 19:58:27.056 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 14 19:58:27.056 INFO [stderr]     = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Dec 14 19:58:27.056 INFO [stderr] 
Dec 14 19:58:27.056 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 14 19:58:27.056 INFO [stderr]    --> src/svec/svector.rs:181:9
Dec 14 19:58:27.056 INFO [stderr]     |
Dec 14 19:58:27.056 INFO [stderr] 181 |         write!(f, "]");
Dec 14 19:58:27.056 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 14 19:58:27.059 INFO [stderr]     |
Dec 14 19:58:27.059 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 14 19:58:27.059 INFO [stderr]     = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Dec 14 19:58:27.059 INFO [stderr] 
Dec 14 19:58:33.353 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.27s
Dec 14 19:58:33.365 INFO [stderr] su: No module specific data is present
Dec 14 19:58:34.276 INFO running `"docker" "inspect" "ce145a0ba98296831360fafab08a192fa7917958e19946cd05c7966f0d0beebe"`
Dec 14 19:58:34.625 INFO running `"docker" "rm" "-f" "ce145a0ba98296831360fafab08a192fa7917958e19946cd05c7966f0d0beebe"`
Dec 14 19:58:34.940 INFO [stdout] ce145a0ba98296831360fafab08a192fa7917958e19946cd05c7966f0d0beebe
Dec 14 19:58:34.945 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 14 19:58:35.451 INFO [stdout] 2db9f7e32a427b01d58c565e5e44d194494a8e180b46dc6ee8254b3cde24e57b
Dec 14 19:58:35.453 INFO running `"docker" "start" "-a" "2db9f7e32a427b01d58c565e5e44d194494a8e180b46dc6ee8254b3cde24e57b"`
Dec 14 19:58:37.359 INFO [stderr] usermod: no changes
Dec 14 19:58:37.476 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 14 19:58:37.480 INFO [stderr]      Running /target/debug/deps/shine_graph-db55bf1280e1c5d1
Dec 14 19:58:37.488 INFO [stdout] 
Dec 14 19:58:37.488 INFO [stdout] running 0 tests
Dec 14 19:58:37.488 INFO [stdout] 
Dec 14 19:58:37.488 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:58:37.489 INFO [stdout] 
Dec 14 19:58:37.499 INFO [stderr]      Running /target/debug/deps/bitset-1a8abdd818b16d99
Dec 14 19:58:37.499 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: stress - u8
Dec 14 19:58:37.499 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: set one bit
Dec 14 19:58:37.499 INFO [stdout] 
Dec 14 19:58:37.499 INFO [stdout] running 6 tests
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: simple u8
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: clear - u8
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: clear - u16
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: clear - u32
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: clear - u64
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: clear - u128
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: random stress - u8
Dec 14 19:58:37.519 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: add bits
Dec 14 19:58:37.519 INFO [stdout] test test_clear ... ok
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops random - u8
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: set random bits
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate or
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate and
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops random - u16
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: set random bits
Dec 14 19:58:37.527 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate or
Dec 14 19:58:37.539 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops - u8
Dec 14 19:58:37.539 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops - u16
Dec 14 19:58:37.540 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops - u32
Dec 14 19:58:37.543 INFO [stdout] test test_ops ... ok
Dec 14 19:58:37.543 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops - u64
Dec 14 19:58:37.543 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops - u128
Dec 14 19:58:37.560 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate and
Dec 14 19:58:37.562 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops random - u32
Dec 14 19:58:37.562 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: set random bits
Dec 14 19:58:37.562 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate or
Dec 14 19:58:37.562 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate and
Dec 14 19:58:37.562 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops random - u64
Dec 14 19:58:37.562 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: set random bits
Dec 14 19:58:37.563 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate or
Dec 14 19:58:37.563 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate and
Dec 14 19:58:37.563 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: ops random - u128
Dec 14 19:58:37.563 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: set random bits
Dec 14 19:58:37.571 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate or
Dec 14 19:58:37.571 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate and
Dec 14 19:58:37.571 INFO [stdout] test test_ops_random ... ok
Dec 14 19:58:37.599 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: simple u16
Dec 14 19:58:37.605 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after insertion
Dec 14 19:58:37.628 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: remove half of the bits
Dec 14 19:58:37.630 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after removal
Dec 14 19:58:37.631 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: random stress - u16
Dec 14 19:58:37.632 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: add bits
Dec 14 19:58:37.665 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after insertion
Dec 14 19:58:37.669 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: remove half of the bits
Dec 14 19:58:37.671 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: simple u32
Dec 14 19:58:37.677 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after removal
Dec 14 19:58:37.678 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: random stress - u32
Dec 14 19:58:37.679 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: add bits
Dec 14 19:58:37.715 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after insertion
Dec 14 19:58:37.727 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: simple u64
Dec 14 19:58:37.727 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: remove half of the bits
Dec 14 19:58:37.729 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after removal
Dec 14 19:58:37.730 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: random stress - u64
Dec 14 19:58:37.731 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: add bits
Dec 14 19:58:37.753 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after insertion
Dec 14 19:58:37.765 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: remove half of the bits
Dec 14 19:58:37.771 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after removal
Dec 14 19:58:37.775 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: random stress - u128
Dec 14 19:58:37.779 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: simple u128
Dec 14 19:58:37.795 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: add bits
Dec 14 19:58:37.799 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after insertion
Dec 14 19:58:37.803 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: remove half of the bits
Dec 14 19:58:37.807 INFO [stderr] DEBUG 2018-12-14T19:58:37Z: bitset: iterate after removal
Dec 14 19:58:37.811 INFO [stdout] test test_random_stress ... ok
Dec 14 19:58:37.839 INFO [stdout] test test_simple ... ok
Dec 14 19:58:41.551 INFO [stderr] DEBUG 2018-12-14T19:58:41Z: bitset: set all bits
Dec 14 19:58:45.351 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: stress - u16
Dec 14 19:58:45.351 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set one bit
Dec 14 19:58:45.407 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set all bits
Dec 14 19:58:45.462 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: stress - u32
Dec 14 19:58:45.462 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set one bit
Dec 14 19:58:45.518 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set all bits
Dec 14 19:58:45.574 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: stress - u64
Dec 14 19:58:45.574 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set one bit
Dec 14 19:58:45.627 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set all bits
Dec 14 19:58:45.683 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: stress - u128
Dec 14 19:58:45.683 INFO [stderr] DEBUG 2018-12-14T19:58:45Z: bitset: set one bit
Dec 14 19:58:49.377 INFO [stderr] DEBUG 2018-12-14T19:58:49Z: bitset: set all bits
Dec 14 19:58:53.147 INFO [stdout] test test_stress ... ok
Dec 14 19:58:53.147 INFO [stdout] 
Dec 14 19:58:53.150 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:58:53.151 INFO [stderr]      Running /target/debug/deps/matrixjoin-dc08d69f222c0184
Dec 14 19:58:53.151 INFO [stdout] 
Dec 14 19:58:53.151 INFO [stdout] 
Dec 14 19:58:53.151 INFO [stdout] running 1 test
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: row read
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: empty row, in capacity
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: empty row, outside capacity
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: a filled row, continuous
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: a filled row, sparse
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: row update
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: empty row, in capacity
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: empty row, outside capacity
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: a filled row, continuous
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: a filled row, sparse
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: vec read, mat read
Dec 14 19:58:53.155 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: vec read, mat update
Dec 14 19:58:53.156 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: vec update, mat read
Dec 14 19:58:53.156 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: vec read, mat read, vec read
Dec 14 19:58:53.156 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: matrixjoin: vec write, mat write
Dec 14 19:58:53.162 INFO [stderr]      Running /target/debug/deps/smat-16a9b43b933649ec
Dec 14 19:58:53.162 INFO [stdout] test test_vec_mat_join ... ok
Dec 14 19:58:53.163 INFO [stdout] 
Dec 14 19:58:53.163 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:58:53.163 INFO [stdout] 
Dec 14 19:58:53.166 INFO [stdout] 
Dec 14 19:58:53.167 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: SparseDMatrix
Dec 14 19:58:53.167 INFO [stdout] running 3 tests
Dec 14 19:58:53.167 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: pass: 0
Dec 14 19:58:53.167 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: pass: 1
Dec 14 19:58:53.167 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: SparseDMatrix - big
Dec 14 19:58:53.170 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: SparseDMatrix/CSMatrix
Dec 14 19:58:53.181 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: iterate 1st
Dec 14 19:58:53.181 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: SparseAMatrix
Dec 14 19:58:53.181 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: pass: 0
Dec 14 19:58:53.181 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: pass: 1
Dec 14 19:58:53.182 INFO [stdout] test test_simple ... ok
Dec 14 19:58:53.182 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: iterate mut
Dec 14 19:58:53.183 INFO [stdout] test test_data_iter ... ok
Dec 14 19:58:53.183 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: iterate 2nd
Dec 14 19:58:53.183 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: SparseAMatrix/CSMatrix
Dec 14 19:58:53.183 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: iterate 1st
Dec 14 19:58:53.183 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: iterate mut
Dec 14 19:58:53.183 INFO [stderr] DEBUG 2018-12-14T19:58:53Z: smat: iterate 2nd
Dec 14 19:58:59.018 INFO [stdout] test test_stress ... ok
Dec 14 19:58:59.018 INFO [stdout] 
Dec 14 19:58:59.018 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:58:59.018 INFO [stdout] 
Dec 14 19:58:59.023 INFO [stderr]      Running /target/debug/deps/svec-008bf0a59eed6e57
Dec 14 19:58:59.027 INFO [stdout] 
Dec 14 19:58:59.027 INFO [stdout] running 3 tests
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: SDVector
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: iterate 1st
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: iterate mut
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: iterate 2nd
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: SHVector
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: iterate 1st
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: iterate mut
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: iterate 2nd
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: SDVector
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: pass: 0
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: pass: 1
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: SHVector
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: pass: 0
Dec 14 19:58:59.034 INFO [stderr] DEBUG 2018-12-14T19:58:59Z: svec: pass: 1
Dec 14 19:58:59.034 INFO [stdout] test test_data_iter ... ok
Dec 14 19:58:59.034 INFO [stdout] test test_simple ... ok
Dec 14 19:59:07.467 INFO [stdout] test test_stress ... ok
Dec 14 19:59:07.467 INFO [stdout] 
Dec 14 19:59:07.467 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:59:07.467 INFO [stdout] 
Dec 14 19:59:07.468 INFO [stderr]      Running /target/debug/deps/vectorjoin-236ba5b3a872540f
Dec 14 19:59:07.470 INFO [stdout] 
Dec 14 19:59:07.470 INFO [stdout] running 1 test
Dec 14 19:59:07.471 INFO [stderr] DEBUG 2018-12-14T19:59:07Z: vectorjoin: read
Dec 14 19:59:07.471 INFO [stderr] DEBUG 2018-12-14T19:59:07Z: vectorjoin: update
Dec 14 19:59:07.471 INFO [stderr] DEBUG 2018-12-14T19:59:07Z: vectorjoin: write
Dec 14 19:59:07.471 INFO [stderr] DEBUG 2018-12-14T19:59:07Z: vectorjoin: (read,update,write)
Dec 14 19:59:07.475 INFO [stdout] test test_svec_join ... ok
Dec 14 19:59:07.475 INFO [stdout] 
Dec 14 19:59:07.475 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:59:07.475 INFO [stdout] 
Dec 14 19:59:07.476 INFO [stderr]    Doc-tests shine-graph
Dec 14 19:59:09.137 INFO [stdout] 
Dec 14 19:59:09.137 INFO [stdout] running 0 tests
Dec 14 19:59:09.137 INFO [stdout] 
Dec 14 19:59:09.137 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:59:09.137 INFO [stdout] 
Dec 14 19:59:09.149 INFO [stderr] su: No module specific data is present
Dec 14 19:59:10.036 INFO running `"docker" "inspect" "2db9f7e32a427b01d58c565e5e44d194494a8e180b46dc6ee8254b3cde24e57b"`
Dec 14 19:59:10.316 INFO running `"docker" "rm" "-f" "2db9f7e32a427b01d58c565e5e44d194494a8e180b46dc6ee8254b3cde24e57b"`
Dec 14 19:59:10.711 INFO [stdout] 2db9f7e32a427b01d58c565e5e44d194494a8e180b46dc6ee8254b3cde24e57b