Dec 06 22:58:32.723 INFO testing shine-graph-0.1.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 22:58:32.723 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:33.224 INFO [stdout] 5c68af8d010526ea9a4406cdf6ad124dc95e775318ac07ea7c1eaf17487d72cb
Dec 06 22:58:33.226 INFO running `"docker" "start" "-a" "5c68af8d010526ea9a4406cdf6ad124dc95e775318ac07ea7c1eaf17487d72cb"`
Dec 06 22:58:34.349 INFO [stderr] usermod: no changes
Dec 06 22:58:34.456 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Dec 06 22:58:34.476 INFO [stderr]    Compiling syn v0.15.22
Dec 06 22:58:34.476 INFO [stderr]    Compiling shine-store v0.2.0
Dec 06 22:58:47.112 INFO [stderr]    Compiling shine-graph-macro v0.1.1
Dec 06 22:58:52.308 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:52.309 INFO [stderr]  --> src/lib.rs:5:12
Dec 06 22:58:52.309 INFO [stderr]   |
Dec 06 22:58:52.310 INFO [stderr] 5 | #![feature(tool_lints)]
Dec 06 22:58:52.310 INFO [stderr]   |            ^^^^^^^^^^
Dec 06 22:58:52.310 INFO [stderr]   |
Dec 06 22:58:52.310 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 06 22:58:52.311 INFO [stderr] 
Dec 06 22:58:52.328 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:58:52.329 INFO [stderr]    --> src/svec/svector.rs:176:9
Dec 06 22:58:52.329 INFO [stderr]     |
Dec 06 22:58:52.329 INFO [stderr] 176 |         write!(f, "[");
Dec 06 22:58:52.330 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:58:52.330 INFO [stderr]     |
Dec 06 22:58:52.330 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 06 22:58:52.331 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:58:52.331 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:52.331 INFO [stderr] 
Dec 06 22:58:52.331 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:58:52.332 INFO [stderr]    --> src/svec/svector.rs:181:9
Dec 06 22:58:52.332 INFO [stderr]     |
Dec 06 22:58:52.332 INFO [stderr] 181 |         write!(f, "]");
Dec 06 22:58:52.333 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:58:52.333 INFO [stderr]     |
Dec 06 22:58:52.333 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:58:52.334 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:52.334 INFO [stderr] 
Dec 06 22:58:52.826 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.45s
Dec 06 22:58:52.832 INFO [stderr] su: No module specific data is present
Dec 06 22:58:54.156 INFO running `"docker" "inspect" "5c68af8d010526ea9a4406cdf6ad124dc95e775318ac07ea7c1eaf17487d72cb"`
Dec 06 22:58:54.480 INFO running `"docker" "rm" "-f" "5c68af8d010526ea9a4406cdf6ad124dc95e775318ac07ea7c1eaf17487d72cb"`
Dec 06 22:58:54.800 INFO [stdout] 5c68af8d010526ea9a4406cdf6ad124dc95e775318ac07ea7c1eaf17487d72cb
Dec 06 22:58:54.806 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:55.244 INFO [stdout] a1d933b4b16b6944108be0578711c03df230c8ecb68c4701daf075e930414005
Dec 06 22:58:55.250 INFO running `"docker" "start" "-a" "a1d933b4b16b6944108be0578711c03df230c8ecb68c4701daf075e930414005"`
Dec 06 22:58:56.800 INFO [stderr] usermod: no changes
Dec 06 22:58:56.940 INFO [stderr]    Compiling shine-testutils v0.1.0
Dec 06 22:58:58.729 INFO [stderr]    Compiling shine-graph v0.1.0 (/source)
Dec 06 22:59:03.928 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:59:03.928 INFO [stderr]  --> src/lib.rs:5:12
Dec 06 22:59:03.928 INFO [stderr]   |
Dec 06 22:59:03.928 INFO [stderr] 5 | #![feature(tool_lints)]
Dec 06 22:59:03.928 INFO [stderr]   |            ^^^^^^^^^^
Dec 06 22:59:03.928 INFO [stderr]   |
Dec 06 22:59:03.928 INFO [stderr]   = note: #[warn(stable_features)] on by default
Dec 06 22:59:03.928 INFO [stderr] 
Dec 06 22:59:03.937 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:59:03.937 INFO [stderr]    --> src/svec/svector.rs:176:9
Dec 06 22:59:03.937 INFO [stderr]     |
Dec 06 22:59:03.937 INFO [stderr] 176 |         write!(f, "[");
Dec 06 22:59:03.937 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:59:03.937 INFO [stderr]     |
Dec 06 22:59:03.937 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 06 22:59:03.937 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:59:03.937 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:59:03.937 INFO [stderr] 
Dec 06 22:59:03.937 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 22:59:03.937 INFO [stderr]    --> src/svec/svector.rs:181:9
Dec 06 22:59:03.937 INFO [stderr]     |
Dec 06 22:59:03.937 INFO [stderr] 181 |         write!(f, "]");
Dec 06 22:59:03.937 INFO [stderr]     |         ^^^^^^^^^^^^^^^
Dec 06 22:59:03.937 INFO [stderr]     |
Dec 06 22:59:03.937 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 22:59:03.937 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:59:03.937 INFO [stderr] 
Dec 06 22:59:16.258 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.43s
Dec 06 22:59:16.264 INFO [stderr] su: No module specific data is present
Dec 06 22:59:16.772 INFO running `"docker" "inspect" "a1d933b4b16b6944108be0578711c03df230c8ecb68c4701daf075e930414005"`
Dec 06 22:59:16.909 INFO running `"docker" "rm" "-f" "a1d933b4b16b6944108be0578711c03df230c8ecb68c4701daf075e930414005"`
Dec 06 22:59:17.040 INFO [stdout] a1d933b4b16b6944108be0578711c03df230c8ecb68c4701daf075e930414005
Dec 06 22:59:17.042 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:59:17.356 INFO [stdout] bd87b30bcace21a0b2a5b3f8727a18f4a3526d08d9d73976805731588fc6d943
Dec 06 22:59:17.362 INFO running `"docker" "start" "-a" "bd87b30bcace21a0b2a5b3f8727a18f4a3526d08d9d73976805731588fc6d943"`
Dec 06 22:59:18.116 INFO [stderr] usermod: no changes
Dec 06 22:59:18.196 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 06 22:59:18.200 INFO [stderr]      Running /target/debug/deps/shine_graph-89687eb86bf7182e
Dec 06 22:59:18.202 INFO [stdout] 
Dec 06 22:59:18.202 INFO [stdout] running 0 tests
Dec 06 22:59:18.202 INFO [stdout] 
Dec 06 22:59:18.202 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:18.202 INFO [stdout] 
Dec 06 22:59:18.202 INFO [stderr]      Running /target/debug/deps/bitset-be46d5a9c22cd37f
Dec 06 22:59:18.204 INFO [stdout] 
Dec 06 22:59:18.204 INFO [stdout] running 6 tests
Dec 06 22:59:18.205 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: clear - u8
Dec 06 22:59:18.205 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops - u8
Dec 06 22:59:18.205 INFO [stdout] test test_clear ... ok
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: clear - u16
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: clear - u32
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: clear - u64
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: clear - u128
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: simple u8
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops - u16
Dec 06 22:59:18.206 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops - u32
Dec 06 22:59:18.207 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops - u64
Dec 06 22:59:18.207 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops - u128
Dec 06 22:59:18.207 INFO [stdout] test test_ops ... ok
Dec 06 22:59:18.208 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops random - u8
Dec 06 22:59:18.208 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: random stress - u8
Dec 06 22:59:18.210 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: add bits
Dec 06 22:59:18.212 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: stress - u8
Dec 06 22:59:18.212 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: set one bit
Dec 06 22:59:18.224 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: set random bits
Dec 06 22:59:18.224 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate or
Dec 06 22:59:18.224 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate and
Dec 06 22:59:18.224 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops random - u16
Dec 06 22:59:18.224 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: set random bits
Dec 06 22:59:18.224 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate or
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate and
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops random - u32
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: set random bits
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate or
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate and
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops random - u64
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: set random bits
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate or
Dec 06 22:59:18.232 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate and
Dec 06 22:59:18.238 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: ops random - u128
Dec 06 22:59:18.238 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: set random bits
Dec 06 22:59:18.239 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate or
Dec 06 22:59:18.239 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate and
Dec 06 22:59:18.240 INFO [stdout] test test_ops_random ... ok
Dec 06 22:59:18.240 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: simple u16
Dec 06 22:59:18.248 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after insertion
Dec 06 22:59:18.248 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: remove half of the bits
Dec 06 22:59:18.251 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after removal
Dec 06 22:59:18.251 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: random stress - u16
Dec 06 22:59:18.254 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: add bits
Dec 06 22:59:18.275 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after insertion
Dec 06 22:59:18.275 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: remove half of the bits
Dec 06 22:59:18.275 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: simple u32
Dec 06 22:59:18.275 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after removal
Dec 06 22:59:18.275 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: random stress - u32
Dec 06 22:59:18.275 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: add bits
Dec 06 22:59:18.284 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after insertion
Dec 06 22:59:18.290 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: remove half of the bits
Dec 06 22:59:18.290 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after removal
Dec 06 22:59:18.290 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: random stress - u64
Dec 06 22:59:18.290 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: simple u64
Dec 06 22:59:18.300 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: add bits
Dec 06 22:59:18.300 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after insertion
Dec 06 22:59:18.300 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: remove half of the bits
Dec 06 22:59:18.300 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after removal
Dec 06 22:59:18.300 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: random stress - u128
Dec 06 22:59:18.304 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: add bits
Dec 06 22:59:18.312 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after insertion
Dec 06 22:59:18.312 INFO [stdout] test test_random_stress ... ok
Dec 06 22:59:18.312 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: remove half of the bits
Dec 06 22:59:18.312 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: iterate after removal
Dec 06 22:59:18.318 INFO [stderr] DEBUG 2018-12-06T22:59:18Z: bitset: simple u128
Dec 06 22:59:18.339 INFO [stdout] test test_simple ... ok
Dec 06 22:59:21.748 INFO [stderr] DEBUG 2018-12-06T22:59:21Z: bitset: set all bits
Dec 06 22:59:25.332 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: stress - u16
Dec 06 22:59:25.332 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set one bit
Dec 06 22:59:25.392 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set all bits
Dec 06 22:59:25.436 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: stress - u32
Dec 06 22:59:25.436 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set one bit
Dec 06 22:59:25.480 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set all bits
Dec 06 22:59:25.530 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: stress - u64
Dec 06 22:59:25.530 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set one bit
Dec 06 22:59:25.580 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set all bits
Dec 06 22:59:25.626 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: stress - u128
Dec 06 22:59:25.626 INFO [stderr] DEBUG 2018-12-06T22:59:25Z: bitset: set one bit
Dec 06 22:59:29.075 INFO [stderr] DEBUG 2018-12-06T22:59:29Z: bitset: set all bits
Dec 06 22:59:32.371 INFO [stdout] test test_stress ... ok
Dec 06 22:59:32.371 INFO [stdout] 
Dec 06 22:59:32.371 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:32.371 INFO [stdout] 
Dec 06 22:59:32.372 INFO [stderr]      Running /target/debug/deps/matrixjoin-2948138a3ded8f42
Dec 06 22:59:32.374 INFO [stdout] 
Dec 06 22:59:32.374 INFO [stdout] running 1 test
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: row read
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: empty row, in capacity
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: empty row, outside capacity
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: a filled row, continuous
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: a filled row, sparse
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: row update
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: empty row, in capacity
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: empty row, outside capacity
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: a filled row, continuous
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: a filled row, sparse
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: vec read, mat read
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: vec read, mat update
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: vec update, mat read
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: vec read, mat read, vec read
Dec 06 22:59:32.381 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: matrixjoin: vec write, mat write
Dec 06 22:59:32.381 INFO [stdout] test test_vec_mat_join ... ok
Dec 06 22:59:32.381 INFO [stdout] 
Dec 06 22:59:32.381 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:32.381 INFO [stdout] 
Dec 06 22:59:32.381 INFO [stderr]      Running /target/debug/deps/smat-fb88079c7400038e
Dec 06 22:59:32.383 INFO [stdout] 
Dec 06 22:59:32.384 INFO [stdout] running 3 tests
Dec 06 22:59:32.384 INFO [stdout] test test_data_iter ... ok
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: SparseDMatrix - big
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: SparseDMatrix/CSMatrix
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: iterate 1st
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: iterate mut
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: iterate 2nd
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: SparseAMatrix/CSMatrix
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: iterate 1st
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: iterate mut
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: iterate 2nd
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: SparseDMatrix
Dec 06 22:59:32.384 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: pass: 0
Dec 06 22:59:32.385 INFO [stdout] test test_simple ... ok
Dec 06 22:59:32.385 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: pass: 1
Dec 06 22:59:32.385 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: SparseAMatrix
Dec 06 22:59:32.385 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: pass: 0
Dec 06 22:59:32.385 INFO [stderr] DEBUG 2018-12-06T22:59:32Z: smat: pass: 1
Dec 06 22:59:37.476 INFO [stdout] test test_stress ... ok
Dec 06 22:59:37.476 INFO [stdout] 
Dec 06 22:59:37.476 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:37.476 INFO [stdout] 
Dec 06 22:59:37.476 INFO [stderr]      Running /target/debug/deps/svec-315b3115c04fc3c6
Dec 06 22:59:37.482 INFO [stdout] 
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: SDVector
Dec 06 22:59:37.482 INFO [stdout] running 3 tests
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: pass: 0
Dec 06 22:59:37.482 INFO [stdout] test test_simple ... ok
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: pass: 1
Dec 06 22:59:37.482 INFO [stdout] test test_data_iter ... ok
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: SHVector
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: pass: 0
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: pass: 1
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: SDVector
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: iterate 1st
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: iterate mut
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: iterate 2nd
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: SHVector
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: iterate 1st
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: iterate mut
Dec 06 22:59:37.482 INFO [stderr] DEBUG 2018-12-06T22:59:37Z: svec: iterate 2nd
Dec 06 22:59:45.612 INFO [stdout] test test_stress ... ok
Dec 06 22:59:45.612 INFO [stdout] 
Dec 06 22:59:45.612 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:45.612 INFO [stdout] 
Dec 06 22:59:45.617 INFO [stderr]      Running /target/debug/deps/vectorjoin-939b95ce91024d44
Dec 06 22:59:45.620 INFO [stdout] 
Dec 06 22:59:45.620 INFO [stdout] running 1 test
Dec 06 22:59:45.624 INFO [stdout] test test_svec_join ... ok
Dec 06 22:59:45.624 INFO [stderr] DEBUG 2018-12-06T22:59:45Z: vectorjoin: read
Dec 06 22:59:45.624 INFO [stdout] 
Dec 06 22:59:45.624 INFO [stderr] DEBUG 2018-12-06T22:59:45Z: vectorjoin: update
Dec 06 22:59:45.624 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:45.624 INFO [stderr] DEBUG 2018-12-06T22:59:45Z: vectorjoin: write
Dec 06 22:59:45.624 INFO [stdout] 
Dec 06 22:59:45.624 INFO [stderr] DEBUG 2018-12-06T22:59:45Z: vectorjoin: (read,update,write)
Dec 06 22:59:45.624 INFO [stderr]    Doc-tests shine-graph
Dec 06 22:59:47.187 INFO [stdout] 
Dec 06 22:59:47.187 INFO [stdout] running 0 tests
Dec 06 22:59:47.187 INFO [stdout] 
Dec 06 22:59:47.187 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:59:47.187 INFO [stdout] 
Dec 06 22:59:47.200 INFO [stderr] su: No module specific data is present
Dec 06 22:59:47.709 INFO running `"docker" "inspect" "bd87b30bcace21a0b2a5b3f8727a18f4a3526d08d9d73976805731588fc6d943"`
Dec 06 22:59:47.852 INFO running `"docker" "rm" "-f" "bd87b30bcace21a0b2a5b3f8727a18f4a3526d08d9d73976805731588fc6d943"`
Dec 06 22:59:48.096 INFO [stdout] bd87b30bcace21a0b2a5b3f8727a18f4a3526d08d9d73976805731588fc6d943