[INFO] fetching crate pearl 0.17.0...
[INFO] testing pearl-0.17.0 against master#159ba8a92c9e2fa4121f106176309521f4af87e9+rustflags=-Copt-level=3 for pr-107051
[INFO] extracting crate pearl 0.17.0 into /workspace/builds/worker-0-tc1/source
[INFO] validating manifest of crates.io crate pearl 0.17.0 on toolchain 159ba8a92c9e2fa4121f106176309521f4af87e9
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+159ba8a92c9e2fa4121f106176309521f4af87e9" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate pearl 0.17.0
[INFO] finished tweaking crates.io crate pearl 0.17.0
[INFO] tweaked toml for crates.io crate pearl 0.17.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] crate crates.io crate pearl 0.17.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+159ba8a92c9e2fa4121f106176309521f4af87e9" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded rio v0.9.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "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" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+159ba8a92c9e2fa4121f106176309521f4af87e9" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 33c6b0c35e1d2739005f301faa8c9a7703317ba48ba9f2343878ed3c0089282f
[INFO] running `Command { std: "docker" "start" "-a" "33c6b0c35e1d2739005f301faa8c9a7703317ba48ba9f2343878ed3c0089282f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "33c6b0c35e1d2739005f301faa8c9a7703317ba48ba9f2343878ed3c0089282f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "33c6b0c35e1d2739005f301faa8c9a7703317ba48ba9f2343878ed3c0089282f", kill_on_drop: false }`
[INFO] [stdout] 33c6b0c35e1d2739005f301faa8c9a7703317ba48ba9f2343878ed3c0089282f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+159ba8a92c9e2fa4121f106176309521f4af87e9" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 34b192552248f0991de11acfb1b8fd049b763203fc31fe621d2fed7e029ce637
[INFO] running `Command { std: "docker" "start" "-a" "34b192552248f0991de11acfb1b8fd049b763203fc31fe621d2fed7e029ce637", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.49
[INFO] [stderr]    Compiling unicode-ident v1.0.6
[INFO] [stderr]    Compiling quote v1.0.23
[INFO] [stderr]    Compiling libc v0.2.139
[INFO] [stderr]    Compiling syn v1.0.107
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling futures-core v0.3.25
[INFO] [stderr]    Compiling pin-project-lite v0.2.9
[INFO] [stderr]    Compiling futures-io v0.3.25
[INFO] [stderr]    Compiling crossbeam-utils v0.8.14
[INFO] [stderr]    Compiling log v0.4.17
[INFO] [stderr]    Compiling parking v2.0.0
[INFO] [stderr]    Compiling event-listener v2.5.3
[INFO] [stderr]    Compiling value-bag v1.0.0-alpha.9
[INFO] [stderr]    Compiling fastrand v1.8.0
[INFO] [stderr]    Compiling waker-fn v1.1.0
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-channel v0.3.25
[INFO] [stderr]    Compiling futures-task v0.3.25
[INFO] [stderr]    Compiling iana-time-zone v0.1.53
[INFO] [stderr]    Compiling once_cell v1.17.0
[INFO] [stderr]    Compiling cc v1.0.78
[INFO] [stderr]    Compiling async-task v4.3.0
[INFO] [stderr]    Compiling futures-util v0.3.25
[INFO] [stderr]    Compiling futures-sink v0.3.25
[INFO] [stderr]    Compiling serde v1.0.152
[INFO] [stderr]    Compiling radium v0.7.0
[INFO] [stderr]    Compiling atomic-waker v1.0.0
[INFO] [stderr]    Compiling ahash v0.7.6
[INFO] [stderr]    Compiling thiserror v1.0.38
[INFO] [stderr]    Compiling bytes v1.3.0
[INFO] [stderr]    Compiling async-trait v0.1.60
[INFO] [stderr]    Compiling ppv-lite86 v0.2.17
[INFO] [stderr]    Compiling regex-syntax v0.6.28
[INFO] [stderr]    Compiling tap v1.0.1
[INFO] [stderr]    Compiling anyhow v1.0.68
[INFO] [stderr]    Compiling concurrent-queue v2.0.0
[INFO] [stderr]    Compiling futures-lite v1.12.0
[INFO] [stderr]    Compiling aho-corasick v0.7.20
[INFO] [stderr]    Compiling serde_derive v1.0.152
[INFO] [stderr]    Compiling async-channel v1.8.0
[INFO] [stderr]    Compiling time v0.1.45
[INFO] [stderr]    Compiling async-lock v2.6.0
[INFO] [stderr]    Compiling wyz v0.5.1
[INFO] [stderr]    Compiling slab v0.4.7
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling num-integer v0.1.45
[INFO] [stderr]    Compiling polling v2.5.2
[INFO] [stderr]    Compiling async-io v1.12.0
[INFO] [stderr]    Compiling memoffset v0.6.5
[INFO] [stderr]    Compiling tokio v1.23.0
[INFO] [stderr]    Compiling async-executor v1.5.0
[INFO] [stderr]    Compiling blocking v1.3.0
[INFO] [stderr]    Compiling regex v1.7.0
[INFO] [stderr]    Compiling untrusted v0.7.1
[INFO] [stderr]    Compiling crc-catalog v2.2.0
[INFO] [stderr]    Compiling spin v0.5.2
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling getrandom v0.2.8
[INFO] [stderr]    Compiling socket2 v0.4.7
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling num_cpus v1.15.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling funty v2.0.0
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling nix v0.25.1
[INFO] [stderr]    Compiling chrono v0.4.23
[INFO] [stderr]    Compiling bitvec v1.0.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling rio v0.9.4
[INFO] [stderr]    Compiling crc v3.0.0
[INFO] [stderr]    Compiling static_assertions v1.1.0
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling ring v0.16.20
[INFO] [stderr]    Compiling pearl v0.17.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling ctor v0.1.26
[INFO] [stderr]    Compiling futures-macro v0.3.25
[INFO] [stderr]    Compiling tokio-macros v1.8.2
[INFO] [stderr]    Compiling thiserror-impl v1.0.38
[INFO] [stderr]    Compiling kv-log-macro v1.0.7
[INFO] [stderr]    Compiling async-global-executor v2.3.1
[INFO] [stderr]    Compiling async-std v1.12.0
[INFO] [stderr]    Compiling env_logger v0.9.3
[INFO] [stderr]    Compiling tokio-stream v0.1.11
[INFO] [stderr]    Compiling futures-executor v0.3.25
[INFO] [stderr]    Compiling futures v0.3.25
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3m 27s
[INFO] running `Command { std: "docker" "inspect" "34b192552248f0991de11acfb1b8fd049b763203fc31fe621d2fed7e029ce637", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "34b192552248f0991de11acfb1b8fd049b763203fc31fe621d2fed7e029ce637", kill_on_drop: false }`
[INFO] [stdout] 34b192552248f0991de11acfb1b8fd049b763203fc31fe621d2fed7e029ce637
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+159ba8a92c9e2fa4121f106176309521f4af87e9" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 170d9c05284c7f4bf34b187b7b37e0020dd1d3fee235b478cdb9a0f63080d2cb
[INFO] running `Command { std: "docker" "start" "-a" "170d9c05284c7f4bf34b187b7b37e0020dd1d3fee235b478cdb9a0f63080d2cb", kill_on_drop: false }`
[INFO] [stderr]    Compiling pearl v0.17.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 48.75s
[INFO] running `Command { std: "docker" "inspect" "170d9c05284c7f4bf34b187b7b37e0020dd1d3fee235b478cdb9a0f63080d2cb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "170d9c05284c7f4bf34b187b7b37e0020dd1d3fee235b478cdb9a0f63080d2cb", kill_on_drop: false }`
[INFO] [stdout] 170d9c05284c7f4bf34b187b7b37e0020dd1d3fee235b478cdb9a0f63080d2cb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+159ba8a92c9e2fa4121f106176309521f4af87e9" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e4dc2e01c81ff4b4124285c2a96ade7ca5de00a5671024b4c3c10924a709ab40
[INFO] running `Command { std: "docker" "start" "-a" "e4dc2e01c81ff4b4124285c2a96ade7ca5de00a5671024b4c3c10924a709ab40", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.19s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pearl-ad597bc17cef82fe)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test blob::index::benchmarks::benchmark_from_file ... ignored
[INFO] [stdout] test blob::index::benchmarks::benchmark_from_records ... ignored
[INFO] [stdout] test blob::index::benchmarks::benchmark_get_all ... ignored
[INFO] [stdout] test blob::index::benchmarks::benchmark_get_any ... ignored
[INFO] [stdout] test blob::index::bptree::node::tests::node_key_offset ... ok
[INFO] [stdout] test blob::index::bptree::node::tests::serialize_deserialize_node ... ok
[INFO] [stdout] test filter::range::tests::test_range_index_key_cmp_proper_key ... ok
[INFO] [stdout] test filter::range::tests::test_range_index_key_cmp_wrong_key ... ok
[INFO] [stdout] test build_info::print_build_info ... ok
[INFO] [stdout] test filter::bloom::tests::check_inversed_formula ... ok
[INFO] [stdout] test blob::index::bptree::tests::blob_size_invalidation ... ok
[INFO] [stdout] test blob::index::bptree::tests::check_get_any ... ok
[INFO] [stdout] test blob::index::bptree::tests::magic_byte_corruption ... ok
[INFO] [stdout] test blob::index::bptree::tests::serialize_deserialize_file ... ok
[INFO] [stdout] test blob::index::bptree::tests::preserves_records_order ... ok
[INFO] [stdout] test blob::index::bptree::tests::check_get ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.37s
[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; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/common.rs (/opt/rustwide/target/debug/deps/common-3ca3dc18e033fa0e)
[INFO] [stderr]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-85669cae04f22582)
[INFO] [stdout] 
[INFO] [stdout] running 36 tests
[INFO] [stdout] test test_hash_algorithm_compat ... ok
[INFO] [stdout] test test_index_from_empty_blob ... ok
[INFO] [stdout] test test_blob_header_validation ... ok
[INFO] [stdout] test test_memory_index ... ok
[INFO] [stdout] test test_multithread_read_write ... ok
[INFO] [stdout] test test_read_all_with_deletion_marker_delete_middle ... ok
[INFO] [stdout] test test_index_from_blob ... ok
[INFO] [stdout] test test_read_all_with_deletion_marker_delete_middle_different_blobs ... ok
[INFO] [stdout] test test_read_with ... ok
[INFO] [stdout] test test_on_disk_index ... ok
[INFO] [stdout] test test_blobs_count_random_names ... ok
[INFO] [stdout] test test_storage_close ... ok
[INFO] [stdout] test test_read_all_load_all ... ok
[INFO] [stdout] test test_manual_close_active_blob ... ok
[INFO] [stdout] test test_read_all_find_one_key ... ok
[INFO] [stdout] test test_records_count_in_active ... ok
[INFO] [stdout] test test_storage_init_new ... ok
[INFO] [stdout] test test_storage_multiple_read_write ... ok
[INFO] [stdout] test test_records_count ... ok
[INFO] [stdout] test test_storage_read_write ... ok
[INFO] [stdout] test test_write_with ... ok
[INFO] [stdout] test test_corrupted_index_regeneration ... ok
[INFO] [stderr] [2023-01-21T07:47:09        pearl::blob::file: 48  ERROR] - File "/tmp/pearl_test/111187228/work_dir_lock/test.0.blob" is locked
[INFO] [stdout] test test_mark_as_deleted_single ... ok
[INFO] [stdout] test test_write_with_with_on_disk_index ... ok
[INFO] [stdout] test test_storage_multithread_blob_overflow ... ok
[INFO] [stdout] test test_storage_init_from_existing ... ok
[INFO] [stdout] test test_mark_as_deleted_deferred_dump ... ok
[INFO] [stdout] test test_in_memory_and_disk_records_retrieval ... ok
[INFO] [stdout] test test_write_512_records_with_same_key ... ok
[INFO] [stdout] test test_records_count_detailed ... ok
[INFO] [stdout] test test_check_bloom_filter_generated ... ok
[INFO] [stdout] test test_check_bloom_filter_single ... ok
[INFO] [stdout] test test_check_bloom_filter_init_from_existing ... ok
[INFO] [stdout] test test_check_bloom_filter_multiple ... ok
[INFO] [stdout] test test_check_bloom_filter_multiple_offloaded ... ok
[INFO] [stdout] test test_work_dir_lock has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "e4dc2e01c81ff4b4124285c2a96ade7ca5de00a5671024b4c3c10924a709ab40", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e4dc2e01c81ff4b4124285c2a96ade7ca5de00a5671024b4c3c10924a709ab40", kill_on_drop: false }`
[INFO] [stdout] e4dc2e01c81ff4b4124285c2a96ade7ca5de00a5671024b4c3c10924a709ab40