Nov 17 23:01:15.155 INFO testing shine-graph-0.1.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 23:01:15.156 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 23:01:15.588 INFO [stdout] 9b854406a287830c445244f072a5e23a489f4187c445c4fed07d795f2225b502
Nov 17 23:01:15.590 INFO running `"docker" "start" "-a" "9b854406a287830c445244f072a5e23a489f4187c445c4fed07d795f2225b502"`
Nov 17 23:01:17.491 INFO [stderr] usermod: no changes
Nov 17 23:01:17.626 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Nov 17 23:01:17.626 INFO [stderr]    Compiling shine-store v0.2.0
Nov 17 23:01:17.626 INFO [stderr]    Compiling syn v0.15.21
Nov 17 23:01:37.057 INFO [stderr]    Compiling shine-graph-macro v0.1.1
Nov 17 23:01:45.199 INFO [stderr] warning: the feature `tool_lints` has been stable since 1.31.0 and no longer requires an attribute to enable
Nov 17 23:01:45.199 INFO [stderr]  --> src/lib.rs:5:12
Nov 17 23:01:45.199 INFO [stderr]   |
Nov 17 23:01:45.199 INFO [stderr] 5 | #![feature(tool_lints)]
Nov 17 23:01:45.199 INFO [stderr]   |            ^^^^^^^^^^
Nov 17 23:01:45.199 INFO [stderr]   |
Nov 17 23:01:45.199 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 23:01:45.199 INFO [stderr] 
Nov 17 23:01:45.207 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 23:01:45.208 INFO [stderr]    --> src/svec/svector.rs:176:9
Nov 17 23:01:45.208 INFO [stderr]     |
Nov 17 23:01:45.208 INFO [stderr] 176 |         write!(f, "[");
Nov 17 23:01:45.209 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Nov 17 23:01:45.209 INFO [stderr]     |
Nov 17 23:01:45.210 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Nov 17 23:01:45.210 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 23:01:45.210 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)
Nov 17 23:01:45.210 INFO [stderr] 
Nov 17 23:01:45.211 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 23:01:45.211 INFO [stderr]    --> src/svec/svector.rs:181:9
Nov 17 23:01:45.211 INFO [stderr]     |
Nov 17 23:01:45.212 INFO [stderr] 181 |         write!(f, "]");
Nov 17 23:01:45.212 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Nov 17 23:01:45.212 INFO [stderr]     |
Nov 17 23:01:45.213 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 23:01:45.213 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)
Nov 17 23:01:45.213 INFO [stderr] 
Nov 17 23:01:45.731 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 28.20s
Nov 17 23:01:45.737 INFO [stderr] su: No module specific data is present
Nov 17 23:01:46.296 INFO running `"docker" "inspect" "9b854406a287830c445244f072a5e23a489f4187c445c4fed07d795f2225b502"`
Nov 17 23:01:46.509 INFO running `"docker" "rm" "-f" "9b854406a287830c445244f072a5e23a489f4187c445c4fed07d795f2225b502"`
Nov 17 23:01:46.735 INFO [stdout] 9b854406a287830c445244f072a5e23a489f4187c445c4fed07d795f2225b502
Nov 17 23:01:46.739 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 23:01:47.054 INFO [stdout] 2324d54e295e568c56066a141a0c16e273af2fdb1e296fee1eb44dea515867a6
Nov 17 23:01:47.057 INFO running `"docker" "start" "-a" "2324d54e295e568c56066a141a0c16e273af2fdb1e296fee1eb44dea515867a6"`
Nov 17 23:01:48.395 INFO [stderr] usermod: no changes
Nov 17 23:01:48.613 INFO [stderr]    Compiling shine-testutils v0.1.0
Nov 17 23:01:50.029 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Nov 17 23:02:03.535 INFO [stderr] warning: the feature `tool_lints` has been stable since 1.31.0 and no longer requires an attribute to enable
Nov 17 23:02:03.536 INFO [stderr]  --> src/lib.rs:5:12
Nov 17 23:02:03.536 INFO [stderr]   |
Nov 17 23:02:03.537 INFO [stderr] 5 | #![feature(tool_lints)]
Nov 17 23:02:03.538 INFO [stderr]   |            ^^^^^^^^^^
Nov 17 23:02:03.551 INFO [stderr]   |
Nov 17 23:02:03.552 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 23:02:03.552 INFO [stderr] 
Nov 17 23:02:03.578 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 23:02:03.579 INFO [stderr]    --> src/svec/svector.rs:176:9
Nov 17 23:02:03.580 INFO [stderr]     |
Nov 17 23:02:03.580 INFO [stderr] 176 |         write!(f, "[");
Nov 17 23:02:03.581 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Nov 17 23:02:03.589 INFO [stderr]     |
Nov 17 23:02:03.589 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Nov 17 23:02:03.589 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 23:02:03.589 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)
Nov 17 23:02:03.589 INFO [stderr] 
Nov 17 23:02:03.590 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 23:02:03.590 INFO [stderr]    --> src/svec/svector.rs:181:9
Nov 17 23:02:03.590 INFO [stderr]     |
Nov 17 23:02:03.590 INFO [stderr] 181 |         write!(f, "]");
Nov 17 23:02:03.590 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Nov 17 23:02:03.595 INFO [stderr]     |
Nov 17 23:02:03.595 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 23:02:03.595 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)
Nov 17 23:02:03.595 INFO [stderr] 
Nov 17 23:02:08.748 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 20.32s
Nov 17 23:02:08.760 INFO [stderr] su: No module specific data is present
Nov 17 23:02:09.581 INFO running `"docker" "inspect" "2324d54e295e568c56066a141a0c16e273af2fdb1e296fee1eb44dea515867a6"`
Nov 17 23:02:09.672 INFO running `"docker" "rm" "-f" "2324d54e295e568c56066a141a0c16e273af2fdb1e296fee1eb44dea515867a6"`
Nov 17 23:02:09.786 INFO [stdout] 2324d54e295e568c56066a141a0c16e273af2fdb1e296fee1eb44dea515867a6
Nov 17 23:02:09.789 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 23:02:09.973 INFO [stdout] 6e681e5bc15f4f150612637d2890c45f5b838d565a748d13a89ea97d6282ac77
Nov 17 23:02:09.980 INFO running `"docker" "start" "-a" "6e681e5bc15f4f150612637d2890c45f5b838d565a748d13a89ea97d6282ac77"`
Nov 17 23:02:10.423 INFO [stderr] usermod: no changes
Nov 17 23:02:10.566 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Nov 17 23:02:10.587 INFO [stderr]      Running /target/debug/deps/shine_graph-8d002bc3129ef6bf
Nov 17 23:02:10.588 INFO [stdout] 
Nov 17 23:02:10.588 INFO [stderr]      Running /target/debug/deps/bitset-773f94aec8d79f35
Nov 17 23:02:10.588 INFO [stdout] running 0 tests
Nov 17 23:02:10.589 INFO [stdout] 
Nov 17 23:02:10.589 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:10.589 INFO [stdout] 
Nov 17 23:02:10.595 INFO [stdout] 
Nov 17 23:02:10.595 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: stress - u8
Nov 17 23:02:10.596 INFO [stdout] running 6 tests
Nov 17 23:02:10.596 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: set one bit
Nov 17 23:02:10.599 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: simple u8
Nov 17 23:02:10.603 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: random stress - u8
Nov 17 23:02:10.605 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: add bits
Nov 17 23:02:10.623 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops random - u8
Nov 17 23:02:10.624 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: set random bits
Nov 17 23:02:10.631 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate or
Nov 17 23:02:10.631 INFO [stdout] test test_ops ... ok
Nov 17 23:02:10.632 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate and
Nov 17 23:02:10.632 INFO [stdout] test test_clear ... ok
Nov 17 23:02:10.632 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops random - u16
Nov 17 23:02:10.633 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: set random bits
Nov 17 23:02:10.633 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops - u8
Nov 17 23:02:10.633 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops - u16
Nov 17 23:02:10.634 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops - u32
Nov 17 23:02:10.634 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops - u64
Nov 17 23:02:10.634 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops - u128
Nov 17 23:02:10.635 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: clear - u8
Nov 17 23:02:10.635 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: clear - u16
Nov 17 23:02:10.635 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: clear - u32
Nov 17 23:02:10.636 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: clear - u64
Nov 17 23:02:10.636 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: clear - u128
Nov 17 23:02:10.651 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate or
Nov 17 23:02:10.651 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate and
Nov 17 23:02:10.652 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops random - u32
Nov 17 23:02:10.652 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: set random bits
Nov 17 23:02:10.652 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate or
Nov 17 23:02:10.653 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate and
Nov 17 23:02:10.653 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops random - u64
Nov 17 23:02:10.653 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: set random bits
Nov 17 23:02:10.654 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate or
Nov 17 23:02:10.658 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate and
Nov 17 23:02:10.695 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: ops random - u128
Nov 17 23:02:10.695 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: set random bits
Nov 17 23:02:10.695 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate or
Nov 17 23:02:10.695 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate and
Nov 17 23:02:10.699 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after insertion
Nov 17 23:02:10.699 INFO [stdout] test test_ops_random ... ok
Nov 17 23:02:10.715 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: remove half of the bits
Nov 17 23:02:10.715 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after removal
Nov 17 23:02:10.715 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: random stress - u16
Nov 17 23:02:10.716 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: add bits
Nov 17 23:02:10.731 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: simple u16
Nov 17 23:02:10.735 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after insertion
Nov 17 23:02:10.735 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: remove half of the bits
Nov 17 23:02:10.735 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after removal
Nov 17 23:02:10.735 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: random stress - u32
Nov 17 23:02:10.743 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: add bits
Nov 17 23:02:10.759 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after insertion
Nov 17 23:02:10.759 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: remove half of the bits
Nov 17 23:02:10.759 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after removal
Nov 17 23:02:10.759 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: random stress - u64
Nov 17 23:02:10.759 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: add bits
Nov 17 23:02:10.795 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after insertion
Nov 17 23:02:10.795 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: remove half of the bits
Nov 17 23:02:10.795 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after removal
Nov 17 23:02:10.803 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: simple u32
Nov 17 23:02:10.807 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: random stress - u128
Nov 17 23:02:10.807 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: add bits
Nov 17 23:02:10.827 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after insertion
Nov 17 23:02:10.847 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: remove half of the bits
Nov 17 23:02:10.847 INFO [stdout] test test_random_stress ... ok
Nov 17 23:02:10.847 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: iterate after removal
Nov 17 23:02:10.847 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: simple u64
Nov 17 23:02:10.883 INFO [stderr] DEBUG 2018-11-17T23:02:10Z: bitset: simple u128
Nov 17 23:02:10.931 INFO [stdout] test test_simple ... ok
Nov 17 23:02:14.419 INFO [stderr] DEBUG 2018-11-17T23:02:14Z: bitset: set all bits
Nov 17 23:02:17.773 INFO [stderr] DEBUG 2018-11-17T23:02:17Z: bitset: stress - u16
Nov 17 23:02:17.773 INFO [stderr] DEBUG 2018-11-17T23:02:17Z: bitset: set one bit
Nov 17 23:02:17.830 INFO [stderr] DEBUG 2018-11-17T23:02:17Z: bitset: set all bits
Nov 17 23:02:17.887 INFO [stderr] DEBUG 2018-11-17T23:02:17Z: bitset: stress - u32
Nov 17 23:02:17.887 INFO [stderr] DEBUG 2018-11-17T23:02:17Z: bitset: set one bit
Nov 17 23:02:17.943 INFO [stderr] DEBUG 2018-11-17T23:02:17Z: bitset: set all bits
Nov 17 23:02:18.000 INFO [stderr] DEBUG 2018-11-17T23:02:18Z: bitset: stress - u64
Nov 17 23:02:18.000 INFO [stderr] DEBUG 2018-11-17T23:02:18Z: bitset: set one bit
Nov 17 23:02:18.059 INFO [stderr] DEBUG 2018-11-17T23:02:18Z: bitset: set all bits
Nov 17 23:02:18.110 INFO [stderr] DEBUG 2018-11-17T23:02:18Z: bitset: stress - u128
Nov 17 23:02:18.110 INFO [stderr] DEBUG 2018-11-17T23:02:18Z: bitset: set one bit
Nov 17 23:02:22.125 INFO [stderr] DEBUG 2018-11-17T23:02:22Z: bitset: set all bits
Nov 17 23:02:25.986 INFO [stdout] test test_stress ... ok
Nov 17 23:02:25.986 INFO [stdout] 
Nov 17 23:02:25.986 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:25.986 INFO [stdout] 
Nov 17 23:02:25.986 INFO [stderr]      Running /target/debug/deps/matrixjoin-e5572d6654e75c60
Nov 17 23:02:25.989 INFO [stdout] 
Nov 17 23:02:25.989 INFO [stdout] running 1 test
Nov 17 23:02:25.990 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: row read
Nov 17 23:02:25.991 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: empty row, in capacity
Nov 17 23:02:25.991 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: empty row, outside capacity
Nov 17 23:02:25.991 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: a filled row, continuous
Nov 17 23:02:25.991 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: a filled row, sparse
Nov 17 23:02:25.991 INFO [stdout] test test_vec_mat_join ... ok
Nov 17 23:02:25.991 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: row update
Nov 17 23:02:25.991 INFO [stdout] 
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: empty row, in capacity
Nov 17 23:02:25.992 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: empty row, outside capacity
Nov 17 23:02:25.992 INFO [stdout] 
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: a filled row, continuous
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: a filled row, sparse
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: vec read, mat read
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: vec read, mat update
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: vec update, mat read
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: vec read, mat read, vec read
Nov 17 23:02:25.992 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: matrixjoin: vec write, mat write
Nov 17 23:02:25.992 INFO [stderr]      Running /target/debug/deps/smat-0569eee554ddd7af
Nov 17 23:02:25.993 INFO [stdout] 
Nov 17 23:02:25.993 INFO [stdout] running 3 tests
Nov 17 23:02:25.994 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: SparseDMatrix/CSMatrix
Nov 17 23:02:25.994 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: SparseDMatrix - big
Nov 17 23:02:25.994 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: iterate 1st
Nov 17 23:02:25.994 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: iterate mut
Nov 17 23:02:25.994 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: iterate 2nd
Nov 17 23:02:25.999 INFO [stdout] test test_data_iter ... ok
Nov 17 23:02:25.999 INFO [stdout] test test_simple ... ok
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: SparseAMatrix/CSMatrix
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: iterate 1st
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: iterate mut
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: iterate 2nd
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: SparseDMatrix
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: pass: 0
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: pass: 1
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: SparseAMatrix
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: pass: 0
Nov 17 23:02:25.999 INFO [stderr] DEBUG 2018-11-17T23:02:25Z: smat: pass: 1
Nov 17 23:02:30.775 INFO [stdout] test test_stress ... ok
Nov 17 23:02:30.775 INFO [stdout] 
Nov 17 23:02:30.775 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:30.775 INFO [stdout] 
Nov 17 23:02:30.776 INFO [stderr]      Running /target/debug/deps/svec-47f8c9b8b71aeea2
Nov 17 23:02:30.777 INFO [stdout] 
Nov 17 23:02:30.777 INFO [stdout] running 3 tests
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: SDVector
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: SDVector
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: pass: 0
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: pass: 1
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: iterate 1st
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: SHVector
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: iterate mut
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: pass: 0
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: iterate 2nd
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: SHVector
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: iterate 1st
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: pass: 1
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: iterate mut
Nov 17 23:02:30.778 INFO [stderr] DEBUG 2018-11-17T23:02:30Z: svec: iterate 2nd
Nov 17 23:02:30.780 INFO [stdout] test test_simple ... ok
Nov 17 23:02:30.780 INFO [stdout] test test_data_iter ... ok
Nov 17 23:02:38.283 INFO [stdout] test test_stress ... ok
Nov 17 23:02:38.283 INFO [stdout] 
Nov 17 23:02:38.283 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:38.283 INFO [stdout] 
Nov 17 23:02:38.283 INFO [stderr]      Running /target/debug/deps/vectorjoin-a26935885f9936fc
Nov 17 23:02:38.285 INFO [stdout] 
Nov 17 23:02:38.285 INFO [stdout] running 1 test
Nov 17 23:02:38.288 INFO [stderr] DEBUG 2018-11-17T23:02:38Z: vectorjoin: read
Nov 17 23:02:38.288 INFO [stderr] DEBUG 2018-11-17T23:02:38Z: vectorjoin: update
Nov 17 23:02:38.288 INFO [stderr] DEBUG 2018-11-17T23:02:38Z: vectorjoin: write
Nov 17 23:02:38.288 INFO [stderr] DEBUG 2018-11-17T23:02:38Z: vectorjoin: (read,update,write)
Nov 17 23:02:38.288 INFO [stderr]    Doc-tests shine-graph
Nov 17 23:02:38.288 INFO [stdout] test test_svec_join ... ok
Nov 17 23:02:38.288 INFO [stdout] 
Nov 17 23:02:38.288 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:38.288 INFO [stdout] 
Nov 17 23:02:39.404 INFO [stdout] 
Nov 17 23:02:39.404 INFO [stdout] running 0 tests
Nov 17 23:02:39.404 INFO [stdout] 
Nov 17 23:02:39.404 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 23:02:39.405 INFO [stdout] 
Nov 17 23:02:39.415 INFO [stderr] su: No module specific data is present
Nov 17 23:02:39.867 INFO running `"docker" "inspect" "6e681e5bc15f4f150612637d2890c45f5b838d565a748d13a89ea97d6282ac77"`
Nov 17 23:02:40.065 INFO running `"docker" "rm" "-f" "6e681e5bc15f4f150612637d2890c45f5b838d565a748d13a89ea97d6282ac77"`
Nov 17 23:02:40.265 INFO [stdout] 6e681e5bc15f4f150612637d2890c45f5b838d565a748d13a89ea97d6282ac77