[INFO] updating cached repository connec/kvs [INFO] running `"git" "fetch" "--all"` [INFO] [stdout] Fetching origin [INFO] [stderr] From git://github.com/connec/kvs [INFO] [stderr] * branch HEAD -> FETCH_HEAD [INFO] running `"git" "clone" "work/cache/sources/gh/connec/kvs" "work/ex/beta-1.38-1/sources/1.37.0/gh/connec/kvs"` [INFO] [stderr] Cloning into 'work/ex/beta-1.38-1/sources/1.37.0/gh/connec/kvs'... [INFO] [stderr] done. [INFO] running `"git" "clone" "work/cache/sources/gh/connec/kvs" "work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/connec/kvs"` [INFO] [stderr] Cloning into 'work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/connec/kvs'... [INFO] [stderr] done. [INFO] running `"git" "rev-parse" "HEAD"` [INFO] [stdout] afdf5b99b4c0ccf111900cc121486f05e1e54be1 [INFO] sha for GitHub repo connec/kvs: afdf5b99b4c0ccf111900cc121486f05e1e54be1 [INFO] validating manifest of connec/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 connec/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 connec/kvs [INFO] finished frobbing connec/kvs [INFO] frobbed toml for connec/kvs written to work/ex/beta-1.38-1/sources/1.37.0/gh/connec/kvs/Cargo.toml [INFO] started frobbing connec/kvs [INFO] finished frobbing connec/kvs [INFO] frobbed toml for connec/kvs written to work/ex/beta-1.38-1/sources/beta-2019-08-13/gh/connec/kvs/Cargo.toml [INFO] crate connec/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] [stderr] Blocking waiting for file lock on package cache [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-08-13" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] testing connec/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-6/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/connec/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] f049c06b561a8bf07ba9308e11f6080efd2fb84cfeea6d37c6af6360e54b1c2b [INFO] running `"docker" "start" "-a" "f049c06b561a8bf07ba9308e11f6080efd2fb84cfeea6d37c6af6360e54b1c2b"` [INFO] [stderr] Compiling slog v2.4.1 [INFO] [stderr] Compiling historian v4.0.3 [INFO] [stderr] Compiling bincode v1.1.4 [INFO] [stderr] Compiling fs2 v0.4.3 [INFO] [stderr] Compiling isatty v0.1.9 [INFO] [stderr] Compiling fxhash v0.2.1 [INFO] [stderr] Compiling crossbeam-deque v0.6.3 [INFO] [stderr] Compiling term v0.5.2 [INFO] [stderr] Compiling serde_derive v1.0.92 [INFO] [stderr] Compiling rmp v0.8.7 [INFO] [stderr] Compiling chrono v0.4.7 [INFO] [stderr] Compiling tempfile v3.0.8 [INFO] [stderr] Compiling rayon-core v1.5.0 [INFO] [stderr] Compiling slog-async v2.3.0 [INFO] [stderr] Compiling rayon v1.1.0 [INFO] [stderr] Compiling slog-term v2.4.0 [INFO] [stderr] Compiling serde v1.0.92 [INFO] [stderr] Compiling hashbrown v0.3.1 [INFO] [stderr] Compiling serde_bytes v0.11.1 [INFO] [stderr] Compiling rmp-serde v0.13.7 [INFO] [stderr] Compiling pagecache v0.17.0 [INFO] [stderr] Compiling sled v0.24.1 [INFO] [stderr] Compiling kvs v0.1.0 (/opt/crater/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 26s [INFO] running `"docker" "inspect" "f049c06b561a8bf07ba9308e11f6080efd2fb84cfeea6d37c6af6360e54b1c2b"` [INFO] running `"docker" "rm" "-f" "f049c06b561a8bf07ba9308e11f6080efd2fb84cfeea6d37c6af6360e54b1c2b"` [INFO] [stdout] f049c06b561a8bf07ba9308e11f6080efd2fb84cfeea6d37c6af6360e54b1c2b [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-6/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/connec/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] 79c3ccf552e280c9a54afc788d4b4debbad568082161693e6208cc999a34680e [INFO] running `"docker" "start" "-a" "79c3ccf552e280c9a54afc788d4b4debbad568082161693e6208cc999a34680e"` [INFO] [stderr] Compiling cast v0.2.2 [INFO] [stderr] Compiling normalize-line-endings v0.2.2 [INFO] [stderr] Compiling itertools v0.8.0 [INFO] [stderr] Compiling memchr v2.2.0 [INFO] [stderr] Compiling rand_xoshiro v0.1.0 [INFO] [stderr] Compiling float-cmp v0.4.0 [INFO] [stderr] Compiling serde_json v1.0.39 [INFO] [stderr] Compiling aho-corasick v0.7.3 [INFO] [stderr] Compiling csv-core v0.1.5 [INFO] [stderr] Compiling criterion-plot v0.3.1 [INFO] [stderr] Compiling csv v1.0.7 [INFO] [stderr] Compiling regex v1.1.7 [INFO] [stderr] Compiling tinytemplate v1.0.2 [INFO] [stderr] Compiling escargot v0.4.0 [INFO] [stderr] Compiling criterion v0.2.11 [INFO] [stderr] Compiling predicates v1.0.1 [INFO] [stderr] Compiling assert_cmd v0.11.1 [INFO] [stderr] Compiling kvs v0.1.0 (/opt/crater/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 44.54s [INFO] running `"docker" "inspect" "79c3ccf552e280c9a54afc788d4b4debbad568082161693e6208cc999a34680e"` [INFO] running `"docker" "rm" "-f" "79c3ccf552e280c9a54afc788d4b4debbad568082161693e6208cc999a34680e"` [INFO] [stdout] 79c3ccf552e280c9a54afc788d4b4debbad568082161693e6208cc999a34680e [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-6/1.37.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/1.37.0/gh/connec/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] e6324984115056bcd5455b9c942410d3e270833f35ef0fb6c979f4fa2ceff523 [INFO] running `"docker" "start" "-a" "e6324984115056bcd5455b9c942410d3e270833f35ef0fb6c979f4fa2ceff523"` [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.14s [INFO] [stderr] Running /opt/crater/target/debug/deps/kvs-0f890bdb2df5b58a [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] [stdout] [INFO] [stderr] Running /opt/crater/target/debug/deps/kvs_client-b4d002a86e1b5fff [INFO] [stdout] running 0 tests [INFO] [stderr] Running /opt/crater/target/debug/deps/kvs_server-f69f611c4b7e61a2 [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Running /opt/crater/target/debug/deps/cli-a8a8488914d6b625 [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] [stdout] [INFO] [stdout] running 11 tests [INFO] [stdout] test client_cli_invalid_subcommand ... ok [INFO] [stderr] Aug 21 03:11:13.020 INFO Starting engine, path: /tmp/.tmp9ugSkg, engine: kvsAug 21 03:11:13.029 INFO Starting engine, path: /tmp/.tmpuuKeYn, engine: sled, version: 0.1.0 [INFO] [stderr] , version: 0.1.0 [INFO] [stderr] Aug 21 03:11:13.041 INFO Starting server, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:13.053 INFO Starting engine, path: /tmp/.tmpNV24RJ, engine: sled, version: 0.1.0 [INFO] [stdout] test client_cli_no_args ... ok [INFO] [stdout] test client_cli_version ... ok [INFO] [stdout] test server_cli_version ... ok [INFO] [stdout] test client_cli_invalid_rm ... ok [INFO] [stdout] test client_cli_invalid_get ... ok [INFO] [stdout] test client_cli_invalid_set ... ok [INFO] [stderr] Aug 21 03:11:13.641 INFO Starting server, address: 127.0.0.1:4002 [INFO] [stderr] Aug 21 03:11:13.773 INFO Starting server, address: 127.0.0.1:4005 [INFO] [stdout] test cli_log_configuration ... ok [INFO] [stderr] Aug 21 03:11:14.018 DEBG Client connected, peer_addr: 127.0.0.1:57666, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.025 DEBG Closing connection, peer_addr: 127.0.0.1:57666, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.028 DEBG Client connected, peer_addr: 127.0.0.1:57668, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.028 DEBG Closing connection, peer_addr: 127.0.0.1:57668, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.031Aug DEBG Client connected 21, peer_addr: 01273.0:.011.1::5902014, .address0:31 127.0.0.1:4005 DEBG [INFO] [stderr] Client connected, peer_addr: 127.0.0.1:57672, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.031 DEBG Closing connection, peer_addr: 127.0.0.1:57672, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.039 DEBG Closing connection, peer_addr: 127.0.0.1:59020, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.040 DEBG Client connected, peer_addr: 127.0.0.1:57674, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.040 DEBG Closing connection, peer_addr: 127.0.0.1:57674, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.042 DEBG Client connected, peer_addr: 127.0.0.1:59026, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.043 DEBG Closing connection, peer_addr: 127.0.0.1:59026, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.043 DEBG Client connected, peer_addr: 127.0.0.1:57678, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.046 DEBG Closing connection, peer_addr: 127.0.0.1:57678, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.047 INFO Starting engine, path: /tmp/.tmpGpNqg9, engine: kvs, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:14.047 INFO Starting server, address: 127.0.0.1:4002 [INFO] [stderr] Aug 21 03:11:14.048 DEBG Client connected, peer_addr: 127.0.0.1:59030, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.053 DEBG Client connected, peer_addr: 127.0.0.1:57682, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.053 DEBG Closing connection, peer_addr: 127.0.0.1:57682, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.056 DEBG Client connected, peer_addr: 127.0.0.1:57684, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.056 DEBG Closing connection, peer_addr: 127.0.0.1:57684, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.069 DEBG Client connected, peer_addr: 127.0.0.1:57686, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.070 DEBG Closing connection, peer_addr: 127.0.0.1:57686, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.071 DEBG Closing connection, peer_addr: 127.0.0.1:59030, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.079 DEBG Client connected, peer_addr: 127.0.0.1:59038, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.079 DEBG Closing connection, peer_addr: 127.0.0.1:59038, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.081 INFO Starting engine, path: /tmp/.tmp9ugSkg, engine: kvs, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:14.081 INFO Starting server, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.099 DEBG Client connected, peer_addr: 127.0.0.1:59040, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.099 DEBG Closing connection, peer_addr: 127.0.0.1:59040, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.107 DEBG Client connected, peer_addr: 127.0.0.1:59042, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.108 DEBG Closing connection, peer_addr: 127.0.0.1:59042, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.113 DEBG Client connected, peer_addr: 127.0.0.1:59044, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.125 DEBG Closing connection, peer_addr: 127.0.0.1:59044, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.133 DEBG Client connected, peer_addr: 127.0.0.1:59046, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.139 DEBG Closing connection, peer_addr: 127.0.0.1:59046, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:14.158 INFO Starting engine, path: /tmp/.tmpuuKeYn, engine: sled, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:14.764 INFO Starting server, address: 127.0.0.1:4005 [INFO] [stdout] test cli_wrong_engine ... ok [INFO] [stderr] Aug 21 03:11:15.079 DEBG Client connected, peer_addr: 127.0.0.1:57698, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.085 DEBG Closing connection, peer_addr: 127.0.0.1:57698, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.087 DEBG Client connected, peer_addr: 127.0.0.1:57700, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.088 DEBG Closing connection, peer_addr: 127.0.0.1:57700, address: 127.0.0.1:4004 [INFO] [stdout] test cli_access_server_kvs_engine ... ok [INFO] [stderr] Aug 21 03:11:15.147 DEBG Client connected, peer_addr: 127.0.0.1:59052, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.150 DEBG Closing connection, peer_addr: 127.0.0.1:59052, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.164 DEBG Client connected, peer_addr: 127.0.0.1:59054, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.166 DEBG Closing connection, peer_addr: 127.0.0.1:59054, address: 127.0.0.1:4005 [INFO] [stdout] test cli_access_server_sled_engine ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/crater/target/debug/deps/kv_store-cee42d057b972c9c [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test get_stored_value ... ok [INFO] [stdout] test get_non_existent_value ... ok [INFO] [stdout] test overwrite_value ... ok [INFO] [stdout] test remove_non_existent_key ... ok [INFO] [stdout] test remove_key ... ok [INFO] [stderr] Doc-tests kvs [INFO] [stdout] test compaction ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test src/engine/kvs.rs - engine::kvs::Store::open (line 61) ... ok [INFO] [stdout] test src/engine/kvs.rs - engine::kvs::Store::set (line 184) ... ok [INFO] [stdout] test src/engine/kvs.rs - engine::kvs::Store::get (line 162) ... ok [INFO] [stdout] test src/engine/kvs.rs - engine::kvs::Store::remove (line 219) ... ok [INFO] [stdout] test src/engine/kvs.rs - engine::kvs::Store (line 25) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `"docker" "inspect" "e6324984115056bcd5455b9c942410d3e270833f35ef0fb6c979f4fa2ceff523"` [INFO] running `"docker" "rm" "-f" "e6324984115056bcd5455b9c942410d3e270833f35ef0fb6c979f4fa2ceff523"` [INFO] [stdout] e6324984115056bcd5455b9c942410d3e270833f35ef0fb6c979f4fa2ceff523