[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 beta-2019-08-13 for beta-1.38-1 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-4/beta-2019-08-13:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/beta-2019-08-13/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" "+beta-2019-08-13" "build" "--frozen"` [INFO] [stdout] 45efce314fda005d6271b12779f11f0242d776aeb2be24c4d6d9d64a8eff892b [INFO] running `"docker" "start" "-a" "45efce314fda005d6271b12779f11f0242d776aeb2be24c4d6d9d64a8eff892b"` [INFO] [stderr] Compiling syn v0.15.36 [INFO] [stderr] Compiling historian v4.0.3 [INFO] [stderr] Compiling fs2 v0.4.3 [INFO] [stderr] Compiling rmp v0.8.7 [INFO] [stderr] Compiling rayon v1.1.0 [INFO] [stderr] Compiling chrono v0.4.7 [INFO] [stderr] Compiling tempfile v3.0.8 [INFO] [stderr] Compiling slog-term v2.4.0 [INFO] [stderr] Compiling serde_derive v1.0.92 [INFO] [stderr] Compiling serde v1.0.92 [INFO] [stderr] Compiling bincode v1.1.4 [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 27s [INFO] running `"docker" "inspect" "45efce314fda005d6271b12779f11f0242d776aeb2be24c4d6d9d64a8eff892b"` [INFO] running `"docker" "rm" "-f" "45efce314fda005d6271b12779f11f0242d776aeb2be24c4d6d9d64a8eff892b"` [INFO] [stdout] 45efce314fda005d6271b12779f11f0242d776aeb2be24c4d6d9d64a8eff892b [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-4/beta-2019-08-13:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/beta-2019-08-13/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" "+beta-2019-08-13" "test" "--frozen" "--no-run"` [INFO] [stdout] 58d5a97d89cefd1c63e3d7909d48afc0b8f6be26573245044c273997357442c5 [INFO] running `"docker" "start" "-a" "58d5a97d89cefd1c63e3d7909d48afc0b8f6be26573245044c273997357442c5"` [INFO] [stderr] Compiling escargot v0.4.0 [INFO] [stderr] Compiling normalize-line-endings v0.2.2 [INFO] [stderr] Compiling treeline v0.1.0 [INFO] [stderr] Compiling predicates-core v1.0.0 [INFO] [stderr] Compiling memchr v2.2.0 [INFO] [stderr] Compiling rand_xoshiro v0.1.0 [INFO] [stderr] Compiling criterion-plot v0.3.1 [INFO] [stderr] Compiling float-cmp v0.4.0 [INFO] [stderr] Compiling serde_json v1.0.39 [INFO] [stderr] Compiling predicates-tree v1.0.0 [INFO] [stderr] Compiling aho-corasick v0.7.3 [INFO] [stderr] Compiling csv-core v0.1.5 [INFO] [stderr] Compiling csv v1.0.7 [INFO] [stderr] Compiling regex v1.1.7 [INFO] [stderr] Compiling tinytemplate v1.0.2 [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.17s [INFO] running `"docker" "inspect" "58d5a97d89cefd1c63e3d7909d48afc0b8f6be26573245044c273997357442c5"` [INFO] running `"docker" "rm" "-f" "58d5a97d89cefd1c63e3d7909d48afc0b8f6be26573245044c273997357442c5"` [INFO] [stdout] 58d5a97d89cefd1c63e3d7909d48afc0b8f6be26573245044c273997357442c5 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.38-1/worker-4/beta-2019-08-13:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.38-1/sources/beta-2019-08-13/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" "+beta-2019-08-13" "test" "--frozen"` [INFO] [stdout] 24b7b7c9a5e8966b5d66e3589746f92a46498e6882b4f269a9dfa7b26edd038c [INFO] running `"docker" "start" "-a" "24b7b7c9a5e8966b5d66e3589746f92a46498e6882b4f269a9dfa7b26edd038c"` [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.13s [INFO] [stderr] Running /opt/crater/target/debug/deps/kvs-00d7ed077e35e85e [INFO] [stderr] Running /opt/crater/target/debug/deps/kvs_client-7bac6fc074ae5426 [INFO] [stdout] [INFO] [stderr] Running /opt/crater/target/debug/deps/kvs_server-9721940e18f7ef72 [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 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 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-d559fa01e8cca094 [INFO] [stdout] [INFO] [stdout] running 11 tests [INFO] [stdout] test client_cli_invalid_subcommand ... ok [INFO] [stderr] Aug 21 03:11:14.587 INFO Starting engine, path: /tmp/.tmp0jJaJX, engine: sled, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:14.587 INFO Starting engine, path: /tmp/.tmpPOXCJv, engine: kvs, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:14.587 INFO Starting server, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:14.601 INFO Starting engine, path: /tmp/.tmpCysOF1, engine: sled, version: 0.1.0 [INFO] [stdout] test client_cli_invalid_rm ... ok [INFO] [stdout] test client_cli_no_args ... ok [INFO] [stdout] test client_cli_invalid_get ... ok [INFO] [stdout] test client_cli_invalid_set ... ok [INFO] [stdout] test server_cli_version ... ok [INFO] [stdout] test client_cli_version ... ok [INFO] [stderr] Aug 21 03:11:15.280 INFO Starting server, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.353 INFO Starting server, address: 127.0.0.1:4002 [INFO] [stdout] test cli_log_configuration ... ok [INFO] [stderr] Aug 21 03:11:15.589 DEBG Client connected, peer_addr: 127.0.0.1:59056, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.599 INFO Starting engine, path: /tmp/.tmpScders, engine: kvs, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:15.600 INFO Starting server, address: 127.0.0.1:4002 [INFO] [stderr] Aug 21 03:11:15.617 DEBG Closing connection, peer_addr: 127.0.0.1:59056, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.621 DEBG Client connected, peer_addr: 127.0.0.1:57708, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.621 DEBG Closing connection, peer_addr: 127.0.0.1:57708, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.623 DEBG Client connected, peer_addr: 127.0.0.1:59060, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.624 DEBG Closing connection, peer_addr: 127.0.0.1:59060, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.631 DEBG Client connected, peer_addr: 127.0.0.1:57712, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.631 DEBG Closing connection, peer_addr: 127.0.0.1:57712, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.639 DEBG Client connected, peer_addr: 127.0.0.1:59064, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.643 DEBG Closing connection, peer_addr: 127.0.0.1:59064, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.645 DEBG Client connected, peer_addr: 127.0.0.1:57716, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.645 DEBG Closing connection, peer_addr: 127.0.0.1:57716, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.653 DEBG Client connected, peer_addr: 127.0.0.1:59068, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.653 DEBG Closing connection, peer_addr: 127.0.0.1:59068, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.653 DEBG Client connected, peer_addr: 127.0.0.1:59070, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.655 DEBG Client connected, peer_addr: 127.0.0.1:57722, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.655 DEBG Closing connection, peer_addr: 127.0.0.1:57722, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.664 DEBG Closing connection, peer_addr: 127.0.0.1:59070, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.667 DEBG Client connected, peer_addr: 127.0.0.1:59076, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.667 DEBG Closing connection, peer_addr: 127.0.0.1:59076, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.669 DEBG Client connected, peer_addr: 127.0.0.1:57724, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.669 DEBG Closing connection, peer_addr: 127.0.0.1:57724, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.672 DEBG Client connected, peer_addr: 127.0.0.1:59078, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.675 DEBG Client connected, peer_addr: 127.0.0.1:57730, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.675 DEBG Closing connection, peer_addr: 127.0.0.1:57730, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.676 DEBG Closing connection, peer_addr: 127.0.0.1:59078, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.681 DEBG Client connected, peer_addr: 127.0.0.1:57732, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.681 DEBG Closing connection, peer_addr: 127.0.0.1:57732, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.687 DEBG Client connected, peer_addr: 127.0.0.1:59084, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.690 DEBG Closing connection, peer_addr: 127.0.0.1:59084, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:15.699 DEBG Client connected, peer_addr: 127.0.0.1:57736, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.699 DEBG Closing connection, peer_addr: 127.0.0.1:57736, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:15.706 INFO Starting engine, path: /tmp/.tmpCysOF1, engine: sled, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:15.715 INFO Starting engine, path: /tmp/.tmpPOXCJv, engine: kvs, version: 0.1.0 [INFO] [stderr] Aug 21 03:11:15.715 INFO Starting server, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:16.196 INFO Starting server, address: 127.0.0.1:4005 [INFO] [stdout] test cli_wrong_engine ... ok [INFO] [stderr] Aug 21 03:11:16.705 DEBG Client connected, peer_addr: 127.0.0.1:59088, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:16.707 DEBG Closing connection, peer_addr: 127.0.0.1:59088, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:16.713 DEBG Client connected, peer_addr: 127.0.0.1:59090, address: 127.0.0.1:4005 [INFO] [stderr] Aug 21 03:11:16.715 DEBG Closing connection, peer_addr: 127.0.0.1:59090, address: 127.0.0.1:4005 [INFO] [stdout] test cli_access_server_sled_engine ... ok [INFO] [stderr] Aug 21 03:11:16.725 DEBG Client connected, peer_addr: 127.0.0.1:57742, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:16.725 DEBG Closing connection, peer_addr: 127.0.0.1:57742, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:16.733 DEBG Client connected, peer_addr: 127.0.0.1:57744, address: 127.0.0.1:4004 [INFO] [stderr] Aug 21 03:11:16.733 DEBG Closing connection, peer_addr: 127.0.0.1:57744, address: 127.0.0.1:4004 [INFO] [stdout] test cli_access_server_kvs_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-b430d7c51d97b44d [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test overwrite_value ... ok [INFO] [stdout] test get_stored_value ... ok [INFO] [stdout] test get_non_existent_value ... ok [INFO] [stdout] test remove_non_existent_key ... ok [INFO] [stdout] test remove_key ... ok [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] [stderr] Doc-tests kvs [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test src/engine/kvs.rs - engine::kvs::Store::remove (line 219) ... 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::open (line 61) ... 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 (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" "24b7b7c9a5e8966b5d66e3589746f92a46498e6882b4f269a9dfa7b26edd038c"` [INFO] running `"docker" "rm" "-f" "24b7b7c9a5e8966b5d66e3589746f92a46498e6882b4f269a9dfa7b26edd038c"` [INFO] [stdout] 24b7b7c9a5e8966b5d66e3589746f92a46498e6882b4f269a9dfa7b26edd038c