[INFO] crate pearl 0.3.3 is already in cache [INFO] testing pearl-0.3.3 against master#8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e for pr-74409 [INFO] extracting crate pearl 0.3.3 into /workspace/builds/worker-7/source [INFO] validating manifest of crates.io crate pearl 0.3.3 on toolchain 8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] started tweaking crates.io crate pearl 0.3.3 [INFO] finished tweaking crates.io crate pearl 0.3.3 [INFO] tweaked toml for crates.io crate pearl 0.3.3 written to /workspace/builds/worker-7/source/Cargo.toml [INFO] crate crates.io crate pearl 0.3.3 already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 21848556b21c88957928c533f9135fe89866012361e1c99bbed5d860430ae8e7 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "21848556b21c88957928c533f9135fe89866012361e1c99bbed5d860430ae8e7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "21848556b21c88957928c533f9135fe89866012361e1c99bbed5d860430ae8e7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "21848556b21c88957928c533f9135fe89866012361e1c99bbed5d860430ae8e7", kill_on_drop: false }` [INFO] [stdout] 21848556b21c88957928c533f9135fe89866012361e1c99bbed5d860430ae8e7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 2b19767486705eb12a8246f16fc439a700e295733685e3494647e2e42882a412 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "2b19767486705eb12a8246f16fc439a700e295733685e3494647e2e42882a412", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.71 [INFO] [stderr] Compiling proc-macro2 v1.0.17 [INFO] [stderr] Compiling unicode-xid v0.2.0 [INFO] [stderr] Compiling syn v1.0.27 [INFO] [stderr] Compiling memchr v2.3.3 [INFO] [stderr] Compiling getrandom v0.1.14 [INFO] [stderr] Compiling autocfg v1.0.0 [INFO] [stderr] Compiling proc-macro-hack v0.5.16 [INFO] [stderr] Compiling futures-core v0.3.5 [INFO] [stderr] Compiling proc-macro-nested v0.1.4 [INFO] [stderr] Compiling once_cell v1.4.0 [INFO] [stderr] Compiling slab v0.4.2 [INFO] [stderr] Compiling futures-sink v0.3.5 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling serde v1.0.110 [INFO] [stderr] Compiling log v0.4.8 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling futures-io v0.3.5 [INFO] [stderr] Compiling ppv-lite86 v0.2.8 [INFO] [stderr] Compiling regex-syntax v0.6.17 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling bytes v0.5.4 [INFO] [stderr] Compiling termcolor v1.1.0 [INFO] [stderr] Compiling pin-project-lite v0.1.5 [INFO] [stderr] Compiling either v1.5.3 [INFO] [stderr] Compiling thread_local v1.0.1 [INFO] [stderr] Compiling futures-channel v0.3.5 [INFO] [stderr] Compiling futures-task v0.3.5 [INFO] [stderr] Compiling humantime v1.3.0 [INFO] [stderr] Compiling bitvec v0.17.4 [INFO] [stderr] Compiling num-traits v0.2.11 [INFO] [stderr] Compiling num-integer v0.1.42 [INFO] [stderr] Compiling aho-corasick v0.7.10 [INFO] [stderr] Compiling quote v1.0.6 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling const-random-macro v0.1.8 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling const-random v0.1.8 [INFO] [stderr] Compiling ahash v0.3.5 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling chrono v0.4.11 [INFO] [stderr] Compiling regex v1.3.7 [INFO] [stderr] Compiling env_logger v0.7.1 [INFO] [stderr] Compiling bincode v1.2.1 [INFO] [stderr] Compiling pin-project-internal v0.4.17 [INFO] [stderr] Compiling futures-macro v0.3.5 [INFO] [stderr] Compiling tokio-macros v0.2.5 [INFO] [stderr] Compiling serde_derive v1.0.110 [INFO] [stderr] Compiling tokio v0.2.21 [INFO] [stderr] Compiling pin-project v0.4.17 [INFO] [stderr] Compiling futures-util v0.3.5 [INFO] [stderr] Compiling futures-executor v0.3.5 [INFO] [stderr] Compiling futures v0.3.5 [INFO] [stderr] Compiling pearl v0.3.3 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 27s [INFO] running `Command { std: "docker" "inspect" "2b19767486705eb12a8246f16fc439a700e295733685e3494647e2e42882a412", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2b19767486705eb12a8246f16fc439a700e295733685e3494647e2e42882a412", kill_on_drop: false }` [INFO] [stdout] 2b19767486705eb12a8246f16fc439a700e295733685e3494647e2e42882a412 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0030afc9dd3de4cbf00c676654df49ac2e0cc7a738bc420fe6f0a80e07da4fd3 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "0030afc9dd3de4cbf00c676654df49ac2e0cc7a738bc420fe6f0a80e07da4fd3", kill_on_drop: false }` [INFO] [stderr] Compiling pearl v0.3.3 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 9.57s [INFO] running `Command { std: "docker" "inspect" "0030afc9dd3de4cbf00c676654df49ac2e0cc7a738bc420fe6f0a80e07da4fd3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0030afc9dd3de4cbf00c676654df49ac2e0cc7a738bc420fe6f0a80e07da4fd3", kill_on_drop: false }` [INFO] [stdout] 0030afc9dd3de4cbf00c676654df49ac2e0cc7a738bc420fe6f0a80e07da4fd3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 2400b7d628f073be6285ca1d8e795f3ceca4cd55fb57afc4f72734422c64b1ea [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "2400b7d628f073be6285ca1d8e795f3ceca4cd55fb57afc4f72734422c64b1ea", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.14s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/pearl-9ad3d616b97e1c7e [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/rustwide/target/debug/deps/common-2d4d6b6de1ea723a [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tests-42dace64f27b584e [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 23 tests [INFO] [stderr] [2020-07-25T04:20:39 tests:373 INFO ] - generate 1000 records with size 3000 [INFO] [stderr] [2020-07-25T04:20:39 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:39 tests:162 WARN ] - elapsed: 0.040 [INFO] [stdout] test test_storage_close ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests:331 WARN ] - elapsed: 0.377 [INFO] [stdout] test test_read_with ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests:617 WARN ] - elapsed: 0.420 [INFO] [stdout] test test_records_count_in_active ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests:128 WARN ] - elapsed: 0.433 [INFO] [stdout] test test_multithread_read_write ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests: 29 WARN ] - elapsed: 0.123 [INFO] [stdout] test test_storage_init_new ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 pearl::blob::index::simple:102 ERROR] - @TODO check consistency [INFO] [stderr] [2020-07-25T04:20:40 pearl::blob::core:128 INFO ] - try regenerate index for blob: test.1.blob [INFO] [stderr] [2020-07-25T04:20:40 tests:199 WARN ] - elapsed: 0.656 [INFO] [stderr] [2020-07-25T04:20:40 pearl::blob::core:128 INFO ] - try regenerate index for blob: test.0.blob [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests: 93 WARN ] - elapsed: 0.272 [INFO] [stdout] test test_storage_multiple_read_write ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::blob::index::simple:102 ERROR] - @TODO check consistency [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests: 66 WARN ] - elapsed: 0.200 [INFO] [stdout] test test_storage_read_write ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:346 ERROR] - working directory is locked: "/tmp/pearl_test/32550840/work_dir_lock/pearl.lock" [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:347 ERROR] - check if any other bob instances are running [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:348 ERROR] - or delete .lock file and try again [INFO] [stdout] test test_on_disk_index ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stdout] test test_work_dir_lock ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests:218 WARN ] - elapsed: 0.124 [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:40 tests:596 WARN ] - elapsed: 0.930 [INFO] [stdout] test test_records_count ... ok [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::observer: 23 ERROR] - Inner(ActiveBlobNotSet) [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::observer: 24 WARN ] - active blob will no longer be updated, shutdown the system [INFO] [stderr] [2020-07-25T04:20:40 pearl::storage::observer: 28 INFO ] - observer stopped [INFO] [stderr] [2020-07-25T04:20:41 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:41 tests:255 WARN ] - elapsed: 0.263 [INFO] [stdout] test test_write_with ... ok [INFO] [stderr] [2020-07-25T04:20:41 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:41 tests:239 WARN ] - elapsed: 1.118 [INFO] [stdout] test test_index_from_blob ... ok [INFO] [stderr] [2020-07-25T04:20:41 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:41 tests:283 WARN ] - elapsed: 1.118 [INFO] [stdout] test test_write_with_with_on_disk_index ... ok [INFO] [stderr] [2020-07-25T04:20:41 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stdout] test test_storage_multithread_blob_overflow ... ok [INFO] [stderr] [2020-07-25T04:20:41 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:41 tests:147 WARN ] - elapsed: 1.607 [INFO] [stderr] [2020-07-25T04:20:42 pearl::blob::index::simple:102 ERROR] - @TODO check consistency [INFO] [stderr] [2020-07-25T04:20:42 pearl::blob::core:128 INFO ] - try regenerate index for blob: test.1.blob [INFO] [stderr] [2020-07-25T04:20:42 pearl::blob::index::simple:102 ERROR] - @TODO check consistency [INFO] [stderr] [2020-07-25T04:20:42 pearl::blob::core:128 INFO ] - try regenerate index for blob: test.0.blob [INFO] [stdout] test test_storage_init_from_existing ... ok [INFO] [stderr] [2020-07-25T04:20:42 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:42 tests: 52 WARN ] - elapsed: 2.365 [INFO] [stderr] [2020-07-25T04:20:42 pearl::blob::index::simple:102 ERROR] - @TODO check consistency [INFO] [stderr] [2020-07-25T04:20:42 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:42 tests:302 WARN ] - elapsed: 2.150 [INFO] [stdout] test test_write_512_records_with_same_key ... ok [INFO] [stdout] test test_records_count_detailed ... FAILED [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::read_all: 75 INFO ] - search for key in active blob [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::read_all: 93 INFO ] - search for key in closed blobs [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:43 tests:403 WARN ] - elapsed: 3.301 [INFO] [stdout] test test_read_all_find_one_key ... ok [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::observer: 23 ERROR] - Inner(ActiveBlobNotSet) [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::observer: 24 WARN ] - active blob will no longer be updated, shutdown the system [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::observer: 28 INFO ] - observer stopped [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:43 tests:522 INFO ] - index file removed [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::observer: 23 ERROR] - Inner(ActiveBlobNotSet) [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::observer: 24 WARN ] - active blob will no longer be updated, shutdown the system [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::observer: 28 INFO ] - observer stopped [INFO] [stdout] test test_check_bloom_filter_single ... ok [INFO] [stderr] [2020-07-25T04:20:43 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:43 tests:427 WARN ] - elapsed: 3.978 [INFO] [stderr] [2020-07-25T04:20:44 pearl::blob::index::simple:102 ERROR] - @TODO check consistency [INFO] [stderr] [2020-07-25T04:20:44 pearl::blob::core:128 INFO ] - try regenerate index for blob: test.0.blob [INFO] [stderr] [2020-07-25T04:20:44 tests:484 INFO ] - check certainly missed keys [INFO] [stderr] [2020-07-25T04:20:44 pearl::blob::core:128 INFO ] - try regenerate index for blob: test.0.blob [INFO] [stderr] [2020-07-25T04:20:44 tests:493 INFO ] - false positive rate: 0.000000 < 0.001 [INFO] [stderr] [2020-07-25T04:20:45 pearl::storage::read_all: 75 INFO ] - search for key in active blob [INFO] [stderr] [2020-07-25T04:20:45 pearl::storage::read_all: 93 INFO ] - search for key in closed blobs [INFO] [stderr] [2020-07-25T04:20:45 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:45 tests:498 WARN ] - elapsed: 5.346 [INFO] [stdout] test test_check_bloom_filter_init_from_existing ... ok [INFO] [stderr] [2020-07-25T04:20:45 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:45 tests:363 WARN ] - elapsed: 5.432 [INFO] [stdout] test test_read_all_load_all ... ok [INFO] [stderr] [2020-07-25T04:20:45 tests:534 INFO ] - check certainly missed keys [INFO] [stderr] [2020-07-25T04:20:45 tests:543 INFO ] - false positive rate: 0.000000 < 0.001 [INFO] [stderr] [2020-07-25T04:20:45 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:45 tests:548 WARN ] - elapsed: 5.798 [INFO] [stdout] test test_check_bloom_filter_generated ... ok [INFO] [stderr] [2020-07-25T04:20:46 pearl::storage::core:304 INFO ] - active blob dumped, lock released [INFO] [stderr] [2020-07-25T04:20:46 tests:451 WARN ] - elapsed: 6.913 [INFO] [stdout] test test_check_bloom_filter_multiple ... ok [INFO] [stderr] error: test failed, to rerun pass '--test tests' [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test_records_count_detailed stdout ---- [INFO] [stdout] thread 'test_records_count_detailed' panicked at 'assertion failed: `(left == right)` [INFO] [stdout] left: `[(0, 20), (1, 10)]`, [INFO] [stdout] right: `[(0, 19), (1, 11)]`', tests/tests.rs:633:5 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55bf7f7ce255 - backtrace::backtrace::libunwind::trace::hea89dfbbfe67f40d [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x55bf7f7ce255 - backtrace::backtrace::trace_unsynchronized::h0513dd713d7644d4 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x55bf7f7ce255 - std::sys_common::backtrace::_print_fmt::h040abae64e4a24a9 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x55bf7f7ce255 - ::fmt::hb4bdb37c3c335733 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x55bf7f7f7f6c - core::fmt::write::h540ac4a6a1232abc [INFO] [stdout] at src/libcore/fmt/mod.rs:1076 [INFO] [stdout] 5: 0x55bf7f4049e5 - std::io::Write::write_fmt::h4be0daababb55bba [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/io/mod.rs:1539 [INFO] [stdout] 6: 0x55bf7f7c7c61 - std::io::impls::>::write_fmt::h83c1e1aa73f374f5 [INFO] [stdout] at src/libstd/io/impls.rs:176 [INFO] [stdout] 7: 0x55bf7f7d0eb0 - std::sys_common::backtrace::_print::hb55cc635eaf1882e [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x55bf7f7d0eb0 - std::sys_common::backtrace::print::h2b2218fa55714084 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x55bf7f7d0eb0 - std::panicking::default_hook::{{closure}}::h578517c025765cf3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x55bf7f7d0baa - std::panicking::default_hook::h4cafc94c2fc640fb [INFO] [stdout] at src/libstd/panicking.rs:214 [INFO] [stdout] 11: 0x55bf7f7d14f3 - std::panicking::rust_panic_with_hook::h3eeb5001beeca5c3 [INFO] [stdout] at src/libstd/panicking.rs:526 [INFO] [stdout] 12: 0x55bf7f7d10eb - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:437 [INFO] [stdout] 13: 0x55bf7f7d105b - std::panicking::begin_panic_fmt::hbb5e59697acc78ba [INFO] [stdout] at src/libstd/panicking.rs:391 [INFO] [stdout] 14: 0x55bf7f31fd42 - tests::test_records_count_detailed::{{closure}}::h893dd2bb3ae6247b [INFO] [stdout] at tests/tests.rs:633 [INFO] [stdout] 15: 0x55bf7f3ff529 - as core::future::future::Future>::poll::h3bd47a881a407a14 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libcore/future/mod.rs:78 [INFO] [stdout] 16: 0x55bf7f3e0523 - tokio::runtime::basic_scheduler::BasicScheduler

::block_on::{{closure}}::{{closure}}::h2110fea626c8cf55 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/basic_scheduler.rs:131 [INFO] [stdout] 17: 0x55bf7f3a43b6 - tokio::coop::with_budget::{{closure}}::h206afdc94b30fde8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/coop.rs:127 [INFO] [stdout] 18: 0x55bf7f3d0811 - std::thread::local::LocalKey::try_with::ha079d5ac05c724de [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/thread/local.rs:263 [INFO] [stdout] 19: 0x55bf7f3c81dd - std::thread::local::LocalKey::with::h2b8518d9830d165e [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/thread/local.rs:239 [INFO] [stdout] 20: 0x55bf7f3d5cdf - tokio::coop::with_budget::h369e3c0306a8aa5e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/coop.rs:120 [INFO] [stdout] 21: 0x55bf7f3d5cdf - tokio::coop::budget::he6d9aea1756e9513 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/coop.rs:96 [INFO] [stdout] 22: 0x55bf7f3d5cdf - tokio::runtime::basic_scheduler::BasicScheduler

::block_on::{{closure}}::h305e85812e520344 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/basic_scheduler.rs:131 [INFO] [stdout] 23: 0x55bf7f3e72b2 - tokio::runtime::basic_scheduler::enter::{{closure}}::h257bea15fd512432 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/basic_scheduler.rs:213 [INFO] [stdout] 24: 0x55bf7f3b3f7f - tokio::macros::scoped_tls::ScopedKey::set::h626be7179f124ff8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/macros/scoped_tls.rs:63 [INFO] [stdout] 25: 0x55bf7f3e3c49 - tokio::runtime::basic_scheduler::enter::h69f7d1405fe29744 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/basic_scheduler.rs:213 [INFO] [stdout] 26: 0x55bf7f3d3c7b - tokio::runtime::basic_scheduler::BasicScheduler

::block_on::h49e76b9214a00500 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/basic_scheduler.rs:123 [INFO] [stdout] 27: 0x55bf7f34d803 - tokio::runtime::Runtime::block_on::{{closure}}::hbd1c51c2416bb68c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/mod.rs:444 [INFO] [stdout] 28: 0x55bf7f3b6d6e - tokio::runtime::context::enter::h8c111e73f829a8e5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/context.rs:72 [INFO] [stdout] 29: 0x55bf7f38654b - tokio::runtime::handle::Handle::enter::h2ad0bb90de91a163 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/handle.rs:76 [INFO] [stdout] 30: 0x55bf7f34c5fb - tokio::runtime::Runtime::block_on::h88e381bdfd4a3b1d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-0.2.21/src/runtime/mod.rs:441 [INFO] [stdout] 31: 0x55bf7f31efc4 - tests::test_records_count_detailed::h24f752817b0daeb4 [INFO] [stdout] at tests/tests.rs:620 [INFO] [stdout] 32: 0x55bf7f31eeda - tests::test_records_count_detailed::{{closure}}::h593d89fba407540a [INFO] [stdout] at tests/tests.rs:620 [INFO] [stdout] 33: 0x55bf7f37393e - core::ops::function::FnOnce::call_once::hb481753913c151ac [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libcore/ops/function.rs:233 [INFO] [stdout] 34: 0x55bf7f42bf5c - as core::ops::function::FnOnce>::call_once::h8340ba6798e0c4ff [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/liballoc/boxed.rs:1081 [INFO] [stdout] 35: 0x55bf7f42bf5c - as core::ops::function::FnOnce<()>>::call_once::h5590e98676605094 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panic.rs:318 [INFO] [stdout] 36: 0x55bf7f42bf5c - std::panicking::try::do_call::hd8680a2dbb0ed3c4 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panicking.rs:348 [INFO] [stdout] 37: 0x55bf7f42bf5c - std::panicking::try::h46b4de2d705e7a8d [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panicking.rs:325 [INFO] [stdout] 38: 0x55bf7f42bf5c - std::panic::catch_unwind::h059b3f06991e0a9c [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panic.rs:394 [INFO] [stdout] 39: 0x55bf7f42bf5c - test::run_test_in_process::h5ae08a65430494a4 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 40: 0x55bf7f42bf5c - test::run_test::run_test_inner::{{closure}}::h4b36073838376ae8 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 41: 0x55bf7f403ed6 - std::sys_common::backtrace::__rust_begin_short_backtrace::h0169d0be588b8bf0 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 42: 0x55bf7f408ec5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h71bfc37e887d3973 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/thread/mod.rs:475 [INFO] [stdout] 43: 0x55bf7f408ec5 - as core::ops::function::FnOnce<()>>::call_once::h36c65072907dc180 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panic.rs:318 [INFO] [stdout] 44: 0x55bf7f408ec5 - std::panicking::try::do_call::h4e0f426e17cd216c [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panicking.rs:348 [INFO] [stdout] 45: 0x55bf7f408ec5 - std::panicking::try::hd05e4ef6b4303d29 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panicking.rs:325 [INFO] [stdout] 46: 0x55bf7f408ec5 - std::panic::catch_unwind::h19a1127ec729c5dd [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/panic.rs:394 [INFO] [stdout] 47: 0x55bf7f408ec5 - std::thread::Builder::spawn_unchecked::{{closure}}::h011300735f06b1e2 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libstd/thread/mod.rs:474 [INFO] [stdout] 48: 0x55bf7f408ec5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h1e3c55f31f1d122e [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/libcore/ops/function.rs:233 [INFO] [stdout] 49: 0x55bf7f7d876a - as core::ops::function::FnOnce>::call_once::hc0e15d78a519721e [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/liballoc/boxed.rs:1081 [INFO] [stdout] 50: 0x55bf7f7d876a - as core::ops::function::FnOnce>::call_once::h211da8fffb09f192 [INFO] [stdout] at /rustc/8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e/src/liballoc/boxed.rs:1081 [INFO] [stdout] 51: 0x55bf7f7d876a - std::sys::unix::thread::Thread::new::thread_start::h29594ddebf23e421 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 52: 0x7fa55139f609 - start_thread [INFO] [stdout] 53: 0x7fa5512ab103 - __clone [INFO] [stdout] 54: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test_records_count_detailed [INFO] [stdout] [INFO] [stdout] test result: FAILED. 22 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "2400b7d628f073be6285ca1d8e795f3ceca4cd55fb57afc4f72734422c64b1ea", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2400b7d628f073be6285ca1d8e795f3ceca4cd55fb57afc4f72734422c64b1ea", kill_on_drop: false }` [INFO] [stdout] 2400b7d628f073be6285ca1d8e795f3ceca4cd55fb57afc4f72734422c64b1ea