[INFO] fetching crate bztree 0.1.0...
[INFO] testing bztree-0.1.0 against beta-2022-02-22 for beta-1.60-1
[INFO] extracting crate bztree 0.1.0 into /workspace/builds/worker-56/source
[INFO] validating manifest of crates.io crate bztree 0.1.0 on toolchain beta-2022-02-22
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate bztree 0.1.0
[INFO] finished tweaking crates.io crate bztree 0.1.0
[INFO] tweaked toml for crates.io crate bztree 0.1.0 written to /workspace/builds/worker-56/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded mwcas v0.1.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 2c2753e46c5079969d7f7fe9c13557cd4554363c45f1fa0edb1794e61c2180d5
[INFO] running `Command { std: "docker" "start" "-a" "2c2753e46c5079969d7f7fe9c13557cd4554363c45f1fa0edb1794e61c2180d5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2c2753e46c5079969d7f7fe9c13557cd4554363c45f1fa0edb1794e61c2180d5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2c2753e46c5079969d7f7fe9c13557cd4554363c45f1fa0edb1794e61c2180d5", kill_on_drop: false }`
[INFO] [stdout] 2c2753e46c5079969d7f7fe9c13557cd4554363c45f1fa0edb1794e61c2180d5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4fb2e232eefd881a4f1d42254bf7bb28b6d71bc87b42a8cc59fa77da05114289
[INFO] running `Command { std: "docker" "start" "-a" "4fb2e232eefd881a4f1d42254bf7bb28b6d71bc87b42a8cc59fa77da05114289", kill_on_drop: false }`
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.7
[INFO] [stderr]    Compiling memoffset v0.6.5
[INFO] [stderr]    Compiling mwcas v0.1.0
[INFO] [stderr]    Compiling bztree v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: associated function is never used: `first_kv`
[INFO] [stdout]    --> src/node.rs:477:12
[INFO] [stdout]     |
[INFO] [stdout] 477 |     pub fn first_kv<'g>(&'g self, guard: &'g Guard) -> Option<(&'g K, &'g V)>
[INFO] [stdout]     |            ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: associated function is never used: `peek_next`
[INFO] [stdout]     --> src/node.rs:1209:12
[INFO] [stdout]      |
[INFO] [stdout] 1209 |     pub fn peek_next(&mut self) -> Option<(&'a K, &'a V)> {
[INFO] [stdout]      |            ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.25s
[INFO] running `Command { std: "docker" "inspect" "4fb2e232eefd881a4f1d42254bf7bb28b6d71bc87b42a8cc59fa77da05114289", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4fb2e232eefd881a4f1d42254bf7bb28b6d71bc87b42a8cc59fa77da05114289", kill_on_drop: false }`
[INFO] [stdout] 4fb2e232eefd881a4f1d42254bf7bb28b6d71bc87b42a8cc59fa77da05114289
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 861f8deaf916cb3fba228f8b466831844a9c3fd087b771e5f8bba946d980ea33
[INFO] running `Command { std: "docker" "start" "-a" "861f8deaf916cb3fba228f8b466831844a9c3fd087b771e5f8bba946d980ea33", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling semver v1.0.6
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling rayon-core v1.9.1
[INFO] [stderr]    Compiling cc v1.0.73
[INFO] [stderr]    Compiling fs_extra v1.2.0
[INFO] [stderr]    Compiling syn v1.0.86
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling ryu v1.0.9
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling itoa v1.0.1
[INFO] [stderr]    Compiling plotters-backend v0.3.2
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling oorandom v11.1.3
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling rayon v1.5.1
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling walkdir v2.3.2
[INFO] [stderr]    Compiling crossbeam-channel v0.5.2
[INFO] [stderr]    Compiling serde_cbor v0.11.2
[INFO] [stderr]    Compiling crossbeam-deque v0.8.1
[INFO] [stdout] warning: associated function is never used: `first_kv`
[INFO] [stdout]    --> src/node.rs:477:12
[INFO] [stdout]     |
[INFO] [stdout] 477 |     pub fn first_kv<'g>(&'g self, guard: &'g Guard) -> Option<(&'g K, &'g V)>
[INFO] [stdout]     |            ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: associated function is never used: `peek_next`
[INFO] [stdout]     --> src/node.rs:1209:12
[INFO] [stdout]      |
[INFO] [stdout] 1209 |     pub fn peek_next(&mut self) -> Option<(&'a K, &'a V)> {
[INFO] [stdout]      |            ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling plotters-svg v0.3.1
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling rustc_version v0.4.0
[INFO] [stderr]    Compiling csv-core v0.1.10
[INFO] [stderr]    Compiling bstr v0.2.17
[INFO] [stderr]    Compiling jemalloc-sys v0.3.2
[INFO] [stderr]    Compiling cast v0.2.7
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling plotters v0.3.1
[INFO] [stderr]    Compiling criterion-plot v0.4.4
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling criterion v0.3.5
[INFO] [stderr]    Compiling jemallocator v0.3.2
[INFO] [stderr]    Compiling bztree v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 57.33s
[INFO] running `Command { std: "docker" "inspect" "861f8deaf916cb3fba228f8b466831844a9c3fd087b771e5f8bba946d980ea33", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "861f8deaf916cb3fba228f8b466831844a9c3fd087b771e5f8bba946d980ea33", kill_on_drop: false }`
[INFO] [stdout] 861f8deaf916cb3fba228f8b466831844a9c3fd087b771e5f8bba946d980ea33
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-56/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2b3c0a52a374425b8c501709804f10a0eb4cbeb9be86ffb33ad751ad146a1871
[INFO] running `Command { std: "docker" "start" "-a" "2b3c0a52a374425b8c501709804f10a0eb4cbeb9be86ffb33ad751ad146a1871", kill_on_drop: false }`
[INFO] [stderr] warning: associated function is never used: `first_kv`
[INFO] [stderr]    --> src/node.rs:477:12
[INFO] [stderr]     |
[INFO] [stderr] 477 |     pub fn first_kv<'g>(&'g self, guard: &'g Guard) -> Option<(&'g K, &'g V)>
[INFO] [stderr]     |            ^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: associated function is never used: `peek_next`
[INFO] [stderr]     --> src/node.rs:1209:12
[INFO] [stderr]      |
[INFO] [stderr] 1209 |     pub fn peek_next(&mut self) -> Option<(&'a K, &'a V)> {
[INFO] [stderr]      |            ^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: `bztree` (lib) generated 2 warnings
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/bztree-1c54bffa4bf5aaf4)
[INFO] [stdout] 
[INFO] [stdout] running 48 tests
[INFO] [stdout] test node::tests::iter_on_empty_sorted_space ... ok
[INFO] [stdout] test node::tests::insert_existing_key ... ok
[INFO] [stdout] test node::tests::iter_reverted ... ok
[INFO] [stdout] test node::tests::iter ... ok
[INFO] [stdout] test node::tests::iter_on_empty_unsorted_space ... ok
[INFO] [stdout] test node::tests::metadata_tests::create_deleted_metadata ... ok
[INFO] [stdout] test node::tests::metadata_tests::create_visible_metadata ... ok
[INFO] [stdout] test node::tests::max ... ok
[INFO] [stdout] test node::tests::metadata_tests::create_reserved_metadata ... ok
[INFO] [stdout] test node::tests::min ... ok
[INFO] [stdout] test node::tests::peek_next ... ok
[INFO] [stdout] test node::tests::peek_next_back ... ok
[INFO] [stdout] test node::tests::range ... ok
[INFO] [stdout] test node::tests::scan_on_sorted_node ... ok
[INFO] [stdout] test status_word::tests::create_empty_status_word ... ok
[INFO] [stdout] test node::tests::scan_on_unsorted_node ... ok
[INFO] [stdout] test node::tests::split_empty_node ... ok
[INFO] [stdout] test status_word::tests::make_frozen ... ok
[INFO] [stdout] test status_word::tests::reserve_entry ... ok
[INFO] [stdout] test node::tests::delete_keys ... ok
[INFO] [stdout] test tests::delete ... ok
[INFO] [stdout] test tests::insert ... ok
[INFO] [stdout] test tests::forward_scan ... ok
[INFO] [stdout] test status_word::tests::create_filled_status_word ... ok
[INFO] [stdout] test tests::mixed_scan_on_root_node ... ok
[INFO] [stdout] test tests::mixed_scan ... ok
[INFO] [stdout] test tests::last ... ok
[INFO] [stdout] test tests::first ... ok
[INFO] [stdout] test tests::test_iter ... ok
[INFO] [stdout] test tests::reversed_scan ... ok
[INFO] [stdout] test tests::test_iter_on_root_node ... ok
[INFO] [stdout] test tests::upsert ... ok
[INFO] [stdout] test tests::reversed_scan_with_deletes ... ok
[INFO] [stdout] test tests::test_rev_iter_on_root_node ... ok
[INFO] [stdout] test tests::scan_after_delete ... ok
[INFO] [stdout] test node::tests::delete_non_existing_keys ... ok
[INFO] [stdout] test tests::scan_outside_of_existing_key_range ... ok
[INFO] [stdout] test node::tests::insert_and_search ... ok
[INFO] [stdout] test node::tests::upsert_and_search ... ok
[INFO] [stdout] test node::tests::merge_nodes ... ok
[INFO] [stdout] test node::tests::try_merge_when_no_space_left ... ok
[INFO] [stdout] test node::tests::update_key ... ok
[INFO] [stdout] test node::tests::split_node ... ok
[INFO] [stdout] test node::tests::compact_node ... ok
[INFO] [stdout] test tests::conditional_insert ... ok
[INFO] [stdout] test tests::pop_last ... ok
[INFO] [stdout] test tests::pop_first ... ok
[INFO] [stdout] test tests::conditional_remove ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.84s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests bztree
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::upsert (line 175) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::get (line 259) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::insert (line 129) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::last (line 359) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::range (line 284) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::first (line 339) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::compute (line 454) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::delete (line 222) ... ok
[INFO] [stdout] test src/lib.rs - (line 9) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::pop_last (line 412) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::iter (line 316) ... ok
[INFO] [stdout] test src/lib.rs - BzTree<K,V>::pop_first (line 379) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.17s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "2b3c0a52a374425b8c501709804f10a0eb4cbeb9be86ffb33ad751ad146a1871", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2b3c0a52a374425b8c501709804f10a0eb4cbeb9be86ffb33ad751ad146a1871", kill_on_drop: false }`
[INFO] [stdout] 2b3c0a52a374425b8c501709804f10a0eb4cbeb9be86ffb33ad751ad146a1871