Dec 06 22:58:25.231 INFO testing shine-graph-0.1.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 22:58:25.231 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 22:58:25.818 INFO [stdout] 29ccdcc4e8e97d70357a5ebb24de0e96b5be6318fdcfe61cac5f4108ef751022
Dec 06 22:58:25.820 INFO running `"docker" "start" "-a" "29ccdcc4e8e97d70357a5ebb24de0e96b5be6318fdcfe61cac5f4108ef751022"`
Dec 06 22:58:28.016 INFO [stderr] usermod: no changes
Dec 06 22:58:28.164 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Dec 06 22:58:28.188 INFO [stderr]    Compiling shine-store v0.2.0
Dec 06 22:58:28.200 INFO [stderr]    Compiling shine-graph-macro v0.1.1
Dec 06 22:58:36.028 INFO [stderr] warning: the feature `tool_lints` has been stable since 1.31.0 and no longer requires an attribute to enable
Dec 06 22:58:36.028 INFO [stderr]  --> src/lib.rs:5:12
Dec 06 22:58:36.028 INFO [stderr]   |
Dec 06 22:58:36.028 INFO [stderr] 5 | #![feature(tool_lints)]
Dec 06 22:58:36.028 INFO [stderr]   |            ^^^^^^^^^^
Dec 06 22:58:36.028 INFO [stderr]   |
Dec 06 22:58:36.028 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 06 22:58:36.028 INFO [stderr] 
Dec 06 22:58:36.036 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:58:36.036 INFO [stderr]    --> src/svec/svector.rs:176:9
Dec 06 22:58:36.036 INFO [stderr]     |
Dec 06 22:58:36.036 INFO [stderr] 176 |         write!(f, "[");
Dec 06 22:58:36.036 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:58:36.036 INFO [stderr]     |
Dec 06 22:58:36.036 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 06 22:58:36.036 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:58:36.036 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 06 22:58:36.036 INFO [stderr] 
Dec 06 22:58:36.036 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:58:36.036 INFO [stderr]    --> src/svec/svector.rs:181:9
Dec 06 22:58:36.036 INFO [stderr]     |
Dec 06 22:58:36.036 INFO [stderr] 181 |         write!(f, "]");
Dec 06 22:58:36.036 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:58:36.036 INFO [stderr]     |
Dec 06 22:58:36.036 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:58:36.036 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 06 22:58:36.036 INFO [stderr] 
Dec 06 22:58:36.559 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.51s
Dec 06 22:58:36.568 INFO [stderr] su: No module specific data is present
Dec 06 22:58:37.324 INFO running `"docker" "inspect" "29ccdcc4e8e97d70357a5ebb24de0e96b5be6318fdcfe61cac5f4108ef751022"`
Dec 06 22:58:37.628 INFO running `"docker" "rm" "-f" "29ccdcc4e8e97d70357a5ebb24de0e96b5be6318fdcfe61cac5f4108ef751022"`
Dec 06 22:58:37.960 INFO [stdout] 29ccdcc4e8e97d70357a5ebb24de0e96b5be6318fdcfe61cac5f4108ef751022
Dec 06 22:58:37.962 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 22:58:38.456 INFO [stdout] 2ae075ddf14b2dfbf2267c6874cf592d5380d8b743034fb30d6aa43ab66d6f8b
Dec 06 22:58:38.458 INFO running `"docker" "start" "-a" "2ae075ddf14b2dfbf2267c6874cf592d5380d8b743034fb30d6aa43ab66d6f8b"`
Dec 06 22:58:39.803 INFO [stderr] usermod: no changes
Dec 06 22:58:39.912 INFO [stderr]    Compiling shine-testutils v0.1.0
Dec 06 22:58:41.784 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Dec 06 22:58:45.652 INFO [stderr] warning: the feature `tool_lints` has been stable since 1.31.0 and no longer requires an attribute to enable
Dec 06 22:58:45.652 INFO [stderr]  --> src/lib.rs:5:12
Dec 06 22:58:45.652 INFO [stderr]   |
Dec 06 22:58:45.652 INFO [stderr] 5 | #![feature(tool_lints)]
Dec 06 22:58:45.652 INFO [stderr]   |            ^^^^^^^^^^
Dec 06 22:58:45.652 INFO [stderr]   |
Dec 06 22:58:45.652 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 06 22:58:45.652 INFO [stderr] 
Dec 06 22:58:45.664 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:58:45.664 INFO [stderr]    --> src/svec/svector.rs:176:9
Dec 06 22:58:45.664 INFO [stderr]     |
Dec 06 22:58:45.664 INFO [stderr] 176 |         write!(f, "[");
Dec 06 22:58:45.664 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:58:45.664 INFO [stderr]     |
Dec 06 22:58:45.664 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 06 22:58:45.664 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:58:45.664 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 06 22:58:45.664 INFO [stderr] 
Dec 06 22:58:45.665 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:58:45.665 INFO [stderr]    --> src/svec/svector.rs:181:9
Dec 06 22:58:45.665 INFO [stderr]     |
Dec 06 22:58:45.665 INFO [stderr] 181 |         write!(f, "]");
Dec 06 22:58:45.665 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:58:45.665 INFO [stderr]     |
Dec 06 22:58:45.666 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:58:45.666 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 06 22:58:45.666 INFO [stderr] 
Dec 06 22:58:56.368 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.53s
Dec 06 22:58:56.388 INFO [stderr] su: No module specific data is present
Dec 06 22:58:57.361 INFO running `"docker" "inspect" "2ae075ddf14b2dfbf2267c6874cf592d5380d8b743034fb30d6aa43ab66d6f8b"`
Dec 06 22:58:57.601 INFO running `"docker" "rm" "-f" "2ae075ddf14b2dfbf2267c6874cf592d5380d8b743034fb30d6aa43ab66d6f8b"`
Dec 06 22:58:57.816 INFO [stdout] 2ae075ddf14b2dfbf2267c6874cf592d5380d8b743034fb30d6aa43ab66d6f8b
Dec 06 22:58:57.822 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 22:58:58.264 INFO [stdout] 2b1063e17c79c84d26278a8edfad1edd4d2f722e2227f2a34a69d0cd66d4d68c
Dec 06 22:58:58.270 INFO running `"docker" "start" "-a" "2b1063e17c79c84d26278a8edfad1edd4d2f722e2227f2a34a69d0cd66d4d68c"`
Dec 06 22:58:59.623 INFO [stderr] usermod: no changes
Dec 06 22:58:59.800 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Dec 06 22:58:59.808 INFO [stderr]      Running /target/debug/deps/shine_graph-1a9161cf3714a948
Dec 06 22:58:59.810 INFO [stdout] 
Dec 06 22:58:59.810 INFO [stdout] running 0 tests
Dec 06 22:58:59.810 INFO [stdout] 
Dec 06 22:58:59.810 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:58:59.810 INFO [stdout] 
Dec 06 22:58:59.815 INFO [stderr]      Running /target/debug/deps/bitset-2ec05b89a5bb860c
Dec 06 22:58:59.815 INFO [stdout] 
Dec 06 22:58:59.815 INFO [stdout] running 6 tests
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: clear - u8
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: clear - u16
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: clear - u32
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: clear - u64
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: clear - u128
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops random - u8
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: set random bits
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate or
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate and
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops random - u16
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: set random bits
Dec 06 22:58:59.820 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate or
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops - u8
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops - u16
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops - u32
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops - u64
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops - u128
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: random stress - u8
Dec 06 22:58:59.828 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: add bits
Dec 06 22:58:59.828 INFO [stdout] test test_clear ... ok
Dec 06 22:58:59.828 INFO [stdout] test test_ops ... ok
Dec 06 22:58:59.829 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: simple u8
Dec 06 22:58:59.829 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: stress - u8
Dec 06 22:58:59.829 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: set one bit
Dec 06 22:58:59.829 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate and
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops random - u32
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: set random bits
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate or
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate and
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops random - u64
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: set random bits
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate or
Dec 06 22:58:59.852 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate and
Dec 06 22:58:59.872 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: ops random - u128
Dec 06 22:58:59.872 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: set random bits
Dec 06 22:58:59.872 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate or
Dec 06 22:58:59.872 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate and
Dec 06 22:58:59.872 INFO [stdout] test test_ops_random ... ok
Dec 06 22:58:59.888 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after insertion
Dec 06 22:58:59.888 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: remove half of the bits
Dec 06 22:58:59.896 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after removal
Dec 06 22:58:59.896 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: simple u16
Dec 06 22:58:59.896 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: random stress - u16
Dec 06 22:58:59.896 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: add bits
Dec 06 22:58:59.916 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after insertion
Dec 06 22:58:59.916 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: remove half of the bits
Dec 06 22:58:59.920 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after removal
Dec 06 22:58:59.920 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: random stress - u32
Dec 06 22:58:59.920 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: add bits
Dec 06 22:58:59.952 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after insertion
Dec 06 22:58:59.952 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: remove half of the bits
Dec 06 22:58:59.952 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after removal
Dec 06 22:58:59.960 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: random stress - u64
Dec 06 22:58:59.960 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: add bits
Dec 06 22:58:59.970 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after insertion
Dec 06 22:58:59.970 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: remove half of the bits
Dec 06 22:58:59.972 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after removal
Dec 06 22:58:59.980 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: simple u32
Dec 06 22:58:59.980 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: random stress - u128
Dec 06 22:58:59.980 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: add bits
Dec 06 22:58:59.994 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after insertion
Dec 06 22:58:59.997 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: remove half of the bits
Dec 06 22:58:59.997 INFO [stderr] DEBUG 2018-12-06T22:58:59Z: bitset: iterate after removal
Dec 06 22:59:00.008 INFO [stdout] test test_random_stress ... ok
Dec 06 22:59:00.024 INFO [stderr] DEBUG 2018-12-06T22:59:00Z: bitset: simple u64
Dec 06 22:59:00.056 INFO [stderr] DEBUG 2018-12-06T22:59:00Z: bitset: simple u128
Dec 06 22:59:00.100 INFO [stdout] test test_simple ... ok
Dec 06 22:59:04.007 INFO [stderr] DEBUG 2018-12-06T22:59:04Z: bitset: set all bits
Dec 06 22:59:07.437 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: stress - u16
Dec 06 22:59:07.437 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set one bit
Dec 06 22:59:07.496 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set all bits
Dec 06 22:59:07.553 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: stress - u32
Dec 06 22:59:07.554 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set one bit
Dec 06 22:59:07.609 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set all bits
Dec 06 22:59:07.664 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: stress - u64
Dec 06 22:59:07.664 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set one bit
Dec 06 22:59:07.718 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set all bits
Dec 06 22:59:07.772 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: stress - u128
Dec 06 22:59:07.772 INFO [stderr] DEBUG 2018-12-06T22:59:07Z: bitset: set one bit
Dec 06 22:59:11.619 INFO [stderr] DEBUG 2018-12-06T22:59:11Z: bitset: set all bits
Dec 06 22:59:15.454 INFO [stdout] test test_stress ... ok
Dec 06 22:59:15.454 INFO [stdout] 
Dec 06 22:59:15.454 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:15.454 INFO [stdout] 
Dec 06 22:59:15.455 INFO [stderr]      Running /target/debug/deps/matrixjoin-e97577cd1e0c72ce
Dec 06 22:59:15.457 INFO [stdout] 
Dec 06 22:59:15.458 INFO [stdout] running 1 test
Dec 06 22:59:15.458 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: row read
Dec 06 22:59:15.458 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: empty row, in capacity
Dec 06 22:59:15.458 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: empty row, outside capacity
Dec 06 22:59:15.459 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: a filled row, continuous
Dec 06 22:59:15.459 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: a filled row, sparse
Dec 06 22:59:15.459 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: row update
Dec 06 22:59:15.459 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: empty row, in capacity
Dec 06 22:59:15.459 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: empty row, outside capacity
Dec 06 22:59:15.459 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: a filled row, continuous
Dec 06 22:59:15.460 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: a filled row, sparse
Dec 06 22:59:15.460 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: vec read, mat read
Dec 06 22:59:15.460 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: vec read, mat update
Dec 06 22:59:15.460 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: vec update, mat read
Dec 06 22:59:15.460 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: vec read, mat read, vec read
Dec 06 22:59:15.460 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: matrixjoin: vec write, mat write
Dec 06 22:59:15.460 INFO [stdout] test test_vec_mat_join ... ok
Dec 06 22:59:15.460 INFO [stdout] 
Dec 06 22:59:15.460 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:15.460 INFO [stdout] 
Dec 06 22:59:15.462 INFO [stderr]      Running /target/debug/deps/smat-38d4268ac81ed0f0
Dec 06 22:59:15.463 INFO [stdout] 
Dec 06 22:59:15.464 INFO [stdout] running 3 tests
Dec 06 22:59:15.464 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: SparseDMatrix - big
Dec 06 22:59:15.464 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: SparseDMatrix/CSMatrix
Dec 06 22:59:15.464 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: iterate 1st
Dec 06 22:59:15.465 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: iterate mut
Dec 06 22:59:15.465 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: iterate 2nd
Dec 06 22:59:15.465 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: SparseAMatrix/CSMatrix
Dec 06 22:59:15.465 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: iterate 1st
Dec 06 22:59:15.465 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: iterate mut
Dec 06 22:59:15.465 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: iterate 2nd
Dec 06 22:59:15.465 INFO [stdout] test test_data_iter ... ok
Dec 06 22:59:15.473 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: SparseDMatrix
Dec 06 22:59:15.473 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: pass: 0
Dec 06 22:59:15.473 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: pass: 1
Dec 06 22:59:15.473 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: SparseAMatrix
Dec 06 22:59:15.473 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: pass: 0
Dec 06 22:59:15.473 INFO [stderr] DEBUG 2018-12-06T22:59:15Z: smat: pass: 1
Dec 06 22:59:15.473 INFO [stdout] test test_simple ... ok
Dec 06 22:59:20.521 INFO [stdout] test test_stress ... ok
Dec 06 22:59:20.521 INFO [stdout] 
Dec 06 22:59:20.521 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:20.521 INFO [stdout] 
Dec 06 22:59:20.522 INFO [stderr]      Running /target/debug/deps/svec-9ac27543821acfd4
Dec 06 22:59:20.524 INFO [stdout] 
Dec 06 22:59:20.524 INFO [stdout] running 3 tests
Dec 06 22:59:20.524 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: SDVector
Dec 06 22:59:20.524 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: pass: 0
Dec 06 22:59:20.524 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: pass: 1
Dec 06 22:59:20.524 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: SHVector
Dec 06 22:59:20.524 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: pass: 0
Dec 06 22:59:20.525 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: pass: 1
Dec 06 22:59:20.525 INFO [stdout] test test_simple ... ok
Dec 06 22:59:20.525 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: SDVector
Dec 06 22:59:20.525 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: iterate 1st
Dec 06 22:59:20.525 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: iterate mut
Dec 06 22:59:20.525 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: iterate 2nd
Dec 06 22:59:20.526 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: SHVector
Dec 06 22:59:20.526 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: iterate 1st
Dec 06 22:59:20.526 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: iterate mut
Dec 06 22:59:20.526 INFO [stderr] DEBUG 2018-12-06T22:59:20Z: svec: iterate 2nd
Dec 06 22:59:20.526 INFO [stdout] test test_data_iter ... ok
Dec 06 22:59:28.236 INFO [stdout] test test_stress ... ok
Dec 06 22:59:28.236 INFO [stderr]      Running /target/debug/deps/vectorjoin-8a5b8fdd8a3d1364
Dec 06 22:59:28.236 INFO [stdout] 
Dec 06 22:59:28.236 INFO [stderr] DEBUG 2018-12-06T22:59:28Z: vectorjoin: read
Dec 06 22:59:28.236 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:28.236 INFO [stderr] DEBUG 2018-12-06T22:59:28Z: vectorjoin: update
Dec 06 22:59:28.236 INFO [stdout] 
Dec 06 22:59:28.236 INFO [stderr] DEBUG 2018-12-06T22:59:28Z: vectorjoin: write
Dec 06 22:59:28.236 INFO [stdout] 
Dec 06 22:59:28.236 INFO [stderr] DEBUG 2018-12-06T22:59:28Z: vectorjoin: (read,update,write)
Dec 06 22:59:28.236 INFO [stdout] running 1 test
Dec 06 22:59:28.236 INFO [stderr]    Doc-tests shine-graph
Dec 06 22:59:28.236 INFO [stdout] test test_svec_join ... ok
Dec 06 22:59:28.236 INFO [stdout] 
Dec 06 22:59:28.236 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:28.236 INFO [stdout] 
Dec 06 22:59:29.506 INFO [stdout] 
Dec 06 22:59:29.506 INFO [stdout] running 0 tests
Dec 06 22:59:29.506 INFO [stdout] 
Dec 06 22:59:29.506 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:29.506 INFO [stdout] 
Dec 06 22:59:29.516 INFO [stderr] su: No module specific data is present
Dec 06 22:59:29.843 INFO running `"docker" "inspect" "2b1063e17c79c84d26278a8edfad1edd4d2f722e2227f2a34a69d0cd66d4d68c"`
Dec 06 22:59:29.964 INFO running `"docker" "rm" "-f" "2b1063e17c79c84d26278a8edfad1edd4d2f722e2227f2a34a69d0cd66d4d68c"`
Dec 06 22:59:30.158 INFO [stdout] 2b1063e17c79c84d26278a8edfad1edd4d2f722e2227f2a34a69d0cd66d4d68c