[INFO] updating cached repository qw4990/kvs
[INFO] running `"git" "fetch" "--all"`
[INFO] [stdout] Fetching origin
[INFO] [stderr] From git://github.com/qw4990/kvs
[INFO] [stderr]  * branch            HEAD       -> FETCH_HEAD
[INFO] running `"git" "clone" "work/cache/sources/gh/qw4990/kvs" "work/ex/beta-1.38-1/sources/1.37.0/gh/qw4990/kvs"`
[INFO] [stderr] Cloning into 'work/ex/beta-1.38-1/sources/1.37.0/gh/qw4990/kvs'...
[INFO] [stderr] done.
[INFO] running `"git" "clone" "work/cache/sources/gh/qw4990/kvs" "work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/qw4990/kvs"`
[INFO] [stderr] Cloning into 'work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/qw4990/kvs'...
[INFO] [stderr] done.
[INFO] running `"git" "rev-parse" "HEAD"`
[INFO] [stdout] c976d535c960094d41ab588ed0217fe4b4efeb2d
[INFO] sha for GitHub repo qw4990/kvs: c976d535c960094d41ab588ed0217fe4b4efeb2d
[INFO] validating manifest of qw4990/kvs on toolchain 1.37.0
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of qw4990/kvs on toolchain beta-2019-08-13
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing qw4990/kvs
[INFO] finished frobbing qw4990/kvs
[INFO] frobbed toml for qw4990/kvs written to work/ex/beta-1.38-1/sources/1.37.0/gh/qw4990/kvs/Cargo.toml
[INFO] started frobbing qw4990/kvs
[INFO] finished frobbing qw4990/kvs
[INFO] frobbed toml for qw4990/kvs written to work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/qw4990/kvs/Cargo.toml
[INFO] crate qw4990/kvs already has a lockfile, it will not be regenerated
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.37.0" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] testing qw4990/kvs against 1.37.0 for beta-1.38-1
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-4/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/qw4990/kvs:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.37.0" "build" "--frozen"`
[INFO] [stdout] acf9b1edd9ff4f22a25c74338c483841735b02f941d43953f420ddff8abc747f
[INFO] running `"docker" "start" "-a" "acf9b1edd9ff4f22a25c74338c483841735b02f941d43953f420ddff8abc747f"`
[INFO] [stderr]    Compiling syn v0.15.34
[INFO] [stderr]    Compiling memchr v2.2.0
[INFO] [stderr]    Compiling aho-corasick v0.7.3
[INFO] [stderr]    Compiling regex v1.1.6
[INFO] [stderr]    Compiling env_logger v0.6.1
[INFO] [stderr]    Compiling synstructure v0.10.2
[INFO] [stderr]    Compiling serde_derive v1.0.92
[INFO] [stderr]    Compiling failure_derive v0.1.5
[INFO] [stderr]    Compiling failure v0.1.5
[INFO] [stderr]    Compiling serde v1.0.92
[INFO] [stderr]    Compiling serde_json v1.0.39
[INFO] [stderr]    Compiling kvs v0.1.0 (/opt/crater/workdir)
[INFO] [stderr] warning: unused import: `std::result`
[INFO] [stderr]  --> src/lib.rs:9:5
[INFO] [stderr]   |
[INFO] [stderr] 9 | use std::result;
[INFO] [stderr]   |     ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `clap::ErrorKind`
[INFO] [stderr]   --> src/lib.rs:11:5
[INFO] [stderr]    |
[INFO] [stderr] 11 | use clap::ErrorKind;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::path`
[INFO] [stderr]  --> src/bin/kvs-client.rs:6:5
[INFO] [stderr]   |
[INFO] [stderr] 6 | use std::path;
[INFO] [stderr]   |     ^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused imports: `KvStore`, `KvsEngine`, `KvsError`
[INFO] [stderr]   --> src/bin/kvs-client.rs:12:19
[INFO] [stderr]    |
[INFO] [stderr] 12 | use kvs::{KvsCmd, KvsEngine, KvsError, KvsResp, KvStore, Result};
[INFO] [stderr]    |                   ^^^^^^^^^  ^^^^^^^^           ^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::env`
[INFO] [stderr]  --> src/bin/kvs-server.rs:6:5
[INFO] [stderr]   |
[INFO] [stderr] 6 | use std::env;
[INFO] [stderr]   |     ^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `log::Level`
[INFO] [stderr]   --> src/bin/kvs-server.rs:13:5
[INFO] [stderr]    |
[INFO] [stderr] 13 | use log::Level;
[INFO] [stderr]    |     ^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused imports: `Deserialize`, `Serialize`
[INFO] [stderr]   --> src/bin/kvs-server.rs:14:13
[INFO] [stderr]    |
[INFO] [stderr] 14 | use serde::{Deserialize, Serialize};
[INFO] [stderr]    |             ^^^^^^^^^^^  ^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `KvsError`
[INFO] [stderr]   --> src/bin/kvs-server.rs:17:30
[INFO] [stderr]    |
[INFO] [stderr] 17 | use kvs::{KvsCmd, KvsEngine, KvsError, KvStore, Result};
[INFO] [stderr]    |                              ^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `resp`
[INFO] [stderr]   --> src/bin/kvs-client.rs:85:9
[INFO] [stderr]    |
[INFO] [stderr] 85 |     let resp = request(db_stream, &KvsCmd::Set { key, val })?;
[INFO] [stderr]    |         ^^^^ help: consider prefixing with an underscore: `_resp`
[INFO] [stderr]    |
[INFO] [stderr]    = note: #[warn(unused_variables)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `resp`
[INFO] [stderr]   --> src/bin/kvs-client.rs:90:9
[INFO] [stderr]    |
[INFO] [stderr] 90 |     let resp = request(db_stream, &KvsCmd::Rm { key })?;
[INFO] [stderr]    |         ^^^^ help: consider prefixing with an underscore: `_resp`
[INFO] [stderr] 
[INFO] [stderr] warning: unreachable pattern
[INFO] [stderr]   --> src/bin/kvs-server.rs:97:17
[INFO] [stderr]    |
[INFO] [stderr] 97 |                 _ => {
[INFO] [stderr]    |                 ^
[INFO] [stderr]    |
[INFO] [stderr]    = note: #[warn(unreachable_patterns)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 02s
[INFO] running `"docker" "inspect" "acf9b1edd9ff4f22a25c74338c483841735b02f941d43953f420ddff8abc747f"`
[INFO] running `"docker" "rm" "-f" "acf9b1edd9ff4f22a25c74338c483841735b02f941d43953f420ddff8abc747f"`
[INFO] [stdout] acf9b1edd9ff4f22a25c74338c483841735b02f941d43953f420ddff8abc747f
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-4/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/qw4990/kvs:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.37.0" "test" "--frozen" "--no-run"`
[INFO] [stdout] 52d82925b9323854d6348c7864c2644456a93346e3bdc487682865ddf01f5d8a
[INFO] running `"docker" "start" "-a" "52d82925b9323854d6348c7864c2644456a93346e3bdc487682865ddf01f5d8a"`
[INFO] [stderr]    Compiling escargot v0.4.0
[INFO] [stderr]    Compiling predicates-core v1.0.0
[INFO] [stderr]    Compiling normalize-line-endings v0.2.2
[INFO] [stderr]    Compiling treeline v0.1.0
[INFO] [stderr]    Compiling crossbeam-epoch v0.3.1
[INFO] [stderr]    Compiling rand_xoshiro v0.1.0
[INFO] [stderr]    Compiling criterion-plot v0.3.1
[INFO] [stderr]    Compiling csv-core v0.1.5
[INFO] [stderr]    Compiling float-cmp v0.4.0
[INFO] [stderr]    Compiling tinytemplate v1.0.2
[INFO] [stderr]    Compiling predicates v1.0.1
[INFO] [stderr]    Compiling predicates-tree v1.0.0
[INFO] [stderr]    Compiling crossbeam-deque v0.2.0
[INFO] [stderr]    Compiling csv v1.0.7
[INFO] [stderr]    Compiling rayon-core v1.4.1
[INFO] [stderr]    Compiling rayon v1.0.3
[INFO] [stderr]    Compiling assert_cmd v0.11.1
[INFO] [stderr]    Compiling criterion v0.2.11
[INFO] [stderr]    Compiling kvs v0.1.0 (/opt/crater/workdir)
[INFO] [stderr] warning: unused import: `std::path`
[INFO] [stderr]  --> src/bin/kvs-client.rs:6:5
[INFO] [stderr]   |
[INFO] [stderr] 6 | use std::path;
[INFO] [stderr]   |     ^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused imports: `KvStore`, `KvsEngine`, `KvsError`
[INFO] [stderr]   --> src/bin/kvs-client.rs:12:19
[INFO] [stderr]    |
[INFO] [stderr] 12 | use kvs::{KvsCmd, KvsEngine, KvsError, KvsResp, KvStore, Result};
[INFO] [stderr]    |                   ^^^^^^^^^  ^^^^^^^^           ^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `resp`
[INFO] [stderr]   --> src/bin/kvs-client.rs:85:9
[INFO] [stderr]    |
[INFO] [stderr] 85 |     let resp = request(db_stream, &KvsCmd::Set { key, val })?;
[INFO] [stderr]    |         ^^^^ help: consider prefixing with an underscore: `_resp`
[INFO] [stderr]    |
[INFO] [stderr]    = note: #[warn(unused_variables)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `resp`
[INFO] [stderr]   --> src/bin/kvs-client.rs:90:9
[INFO] [stderr]    |
[INFO] [stderr] 90 |     let resp = request(db_stream, &KvsCmd::Rm { key })?;
[INFO] [stderr]    |         ^^^^ help: consider prefixing with an underscore: `_resp`
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::env`
[INFO] [stderr]  --> src/bin/kvs-server.rs:6:5
[INFO] [stderr]   |
[INFO] [stderr] 6 | use std::env;
[INFO] [stderr]   |     ^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `log::Level`
[INFO] [stderr]   --> src/bin/kvs-server.rs:13:5
[INFO] [stderr]    |
[INFO] [stderr] 13 | use log::Level;
[INFO] [stderr]    |     ^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused imports: `Deserialize`, `Serialize`
[INFO] [stderr]   --> src/bin/kvs-server.rs:14:13
[INFO] [stderr]    |
[INFO] [stderr] 14 | use serde::{Deserialize, Serialize};
[INFO] [stderr]    |             ^^^^^^^^^^^  ^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `KvsError`
[INFO] [stderr]   --> src/bin/kvs-server.rs:17:30
[INFO] [stderr]    |
[INFO] [stderr] 17 | use kvs::{KvsCmd, KvsEngine, KvsError, KvStore, Result};
[INFO] [stderr]    |                              ^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::result`
[INFO] [stderr]  --> src/lib.rs:9:5
[INFO] [stderr]   |
[INFO] [stderr] 9 | use std::result;
[INFO] [stderr]   |     ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `clap::ErrorKind`
[INFO] [stderr]   --> src/lib.rs:11:5
[INFO] [stderr]    |
[INFO] [stderr] 11 | use clap::ErrorKind;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unreachable pattern
[INFO] [stderr]   --> src/bin/kvs-server.rs:97:17
[INFO] [stderr]    |
[INFO] [stderr] 97 |                 _ => {
[INFO] [stderr]    |                 ^
[INFO] [stderr]    |
[INFO] [stderr]    = note: #[warn(unreachable_patterns)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 40.24s
[INFO] running `"docker" "inspect" "52d82925b9323854d6348c7864c2644456a93346e3bdc487682865ddf01f5d8a"`
[INFO] running `"docker" "rm" "-f" "52d82925b9323854d6348c7864c2644456a93346e3bdc487682865ddf01f5d8a"`
[INFO] [stdout] 52d82925b9323854d6348c7864c2644456a93346e3bdc487682865ddf01f5d8a
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-4/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/qw4990/kvs:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.37.0" "test" "--frozen"`
[INFO] [stdout] ecf7a6629d799208f336e171688c75ccf2d1a5a3f475bf65c78d26262e616609
[INFO] running `"docker" "start" "-a" "ecf7a6629d799208f336e171688c75ccf2d1a5a3f475bf65c78d26262e616609"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/kvs-e8809e738cc3160e
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/kvs_client-7587f61e3a152c5b
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/kvs_server-7d5e7a1cbcb56c5a
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/cli-9614b781ddf736ad
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stderr] [2019-08-19T00:47:45Z ERROR kvs_server] servr starting...
[INFO] [stderr] [2019-08-19T00:47:45Z ERROR kvs_server] Version: "0.1.0", Addr: "127.0.0.1:4004", Engine: "kvs"
[INFO] [stderr] [2019-08-19T00:47:45Z ERROR kvs_server] servr starting...
[INFO] [stderr] [2019-08-19T00:47:45Z ERROR kvs_server] Version: "0.1.0", Addr: "127.0.0.1:4005", Engine: "sled"
[INFO] [stderr] [2019-08-19T00:47:45Z ERROR kvs_server] servr starting...
[INFO] [stderr] [2019-08-19T00:47:45Z ERROR kvs_server] Version: "0.1.0", Addr: "127.0.0.1:4002", Engine: "sled"
[INFO] [stdout] test client_cli_invalid_subcommand ... ok
[INFO] [stdout] test client_cli_no_args ... ok
[INFO] [stdout] test client_cli_version ... ok
[INFO] [stdout] test client_cli_invalid_set ... ok
[INFO] [stdout] test client_cli_invalid_get ... ok
[INFO] [stdout] test client_cli_invalid_rm ... ok
[INFO] [stdout] test server_cli_version ... ok
[INFO] [stdout] test cli_log_configuration ... ok
[INFO] [stdout] test cli_access_server_sled_engine ... FAILED
[INFO] [stdout] test cli_access_server_kvs_engine ... FAILED
[INFO] [stdout] test cli_wrong_engine ... test cli_wrong_engine has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `"docker" "inspect" "ecf7a6629d799208f336e171688c75ccf2d1a5a3f475bf65c78d26262e616609"`
[INFO] running `"docker" "rm" "-f" "ecf7a6629d799208f336e171688c75ccf2d1a5a3f475bf65c78d26262e616609"`
[INFO] [stdout] ecf7a6629d799208f336e171688c75ccf2d1a5a3f475bf65c78d26262e616609