[INFO] fetching crate msg-store 0.3.3...
[INFO] testing msg-store-0.3.3 against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] extracting crate msg-store 0.3.3 into /workspace/builds/worker-111/source
[INFO] validating manifest of crates.io crate msg-store 0.3.3 on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate msg-store 0.3.3
[INFO] finished tweaking crates.io crate msg-store 0.3.3
[INFO] tweaked toml for crates.io crate msg-store 0.3.3 written to /workspace/builds/worker-111/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ee5c5fe01e63eeda55d32b1b00c429d4cc076c9a1909df38baead14728e2d891
[INFO] running `Command { std: "docker" "start" "-a" "ee5c5fe01e63eeda55d32b1b00c429d4cc076c9a1909df38baead14728e2d891", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ee5c5fe01e63eeda55d32b1b00c429d4cc076c9a1909df38baead14728e2d891", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ee5c5fe01e63eeda55d32b1b00c429d4cc076c9a1909df38baead14728e2d891", kill_on_drop: false }`
[INFO] [stdout] ee5c5fe01e63eeda55d32b1b00c429d4cc076c9a1909df38baead14728e2d891
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/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" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cb0e10775dcf33b9d38931646d766338d9f21d718749dbdb26cdcfdd83c6230c
[INFO] running `Command { std: "docker" "start" "-a" "cb0e10775dcf33b9d38931646d766338d9f21d718749dbdb26cdcfdd83c6230c", kill_on_drop: false }`
[INFO] [stderr]    Compiling msg-store v0.3.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.44s
[INFO] running `Command { std: "docker" "inspect" "cb0e10775dcf33b9d38931646d766338d9f21d718749dbdb26cdcfdd83c6230c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cb0e10775dcf33b9d38931646d766338d9f21d718749dbdb26cdcfdd83c6230c", kill_on_drop: false }`
[INFO] [stdout] cb0e10775dcf33b9d38931646d766338d9f21d718749dbdb26cdcfdd83c6230c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/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" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3b6091611ae2e2561ed12ed1678b59b5f6dd21abeb716bf9518fcea9885758de
[INFO] running `Command { std: "docker" "start" "-a" "3b6091611ae2e2561ed12ed1678b59b5f6dd21abeb716bf9518fcea9885758de", kill_on_drop: false }`
[INFO] [stderr]    Compiling msg-store v0.3.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.53s
[INFO] running `Command { std: "docker" "inspect" "3b6091611ae2e2561ed12ed1678b59b5f6dd21abeb716bf9518fcea9885758de", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3b6091611ae2e2561ed12ed1678b59b5f6dd21abeb716bf9518fcea9885758de", kill_on_drop: false }`
[INFO] [stdout] 3b6091611ae2e2561ed12ed1678b59b5f6dd21abeb716bf9518fcea9885758de
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-111/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" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 34072542e502c3c9509a0900e97339575695dead786bf4703bbf56f5800e164a
[INFO] running `Command { std: "docker" "start" "-a" "34072542e502c3c9509a0900e97339575695dead786bf4703bbf56f5800e164a", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/msg_store-8180a66dec3e7cdb)
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test mem::tests::add::should_increase_group_byte_size ... ok
[INFO] [stdout] test mem::tests::add::should_create_group_with_defaults ... ok
[INFO] [stdout] test mem::tests::add::should_increase_inserted_msg_count ... ok
[INFO] [stdout] test mem::tests::add::should_increase_burned_msg_count ... ok
[INFO] [stdout] test mem::tests::add::should_increase_store_byte_size ... ok
[INFO] [stdout] test mem::tests::add::should_prune_group_byte_size_to_10_when_group_max_byte_size_exists ... ok
[INFO] [stdout] test mem::tests::add::should_prune_oldest_msg_in_a_group_when_exceeding_store_max_byte_size ... ok
[INFO] [stdout] test mem::tests::add::should_prune_store_byte_size_to_10_when_group_max_byte_size_exists ... ok
[INFO] [stdout] test mem::tests::add::should_prune_store_byte_size_to_10_when_store_max_byte_size_exists ... ok
[INFO] [stdout] test mem::tests::add::should_reinsert_group_after_errors ... ok
[INFO] [stdout] test mem::tests::add::should_return_msg_lacks_priority_err ... ok
[INFO] [stdout] test mem::tests::add::should_return_msg_to_large_for_group_err ... ok
[INFO] [stdout] test mem::tests::add::should_prune_oldest_msg_in_a_group_when_exceeding_group_max_byte_size ... ok
[INFO] [stdout] test mem::tests::add::should_prune_oldest_lowest_pri_msg_in_the_store_when_exceeding_store_max_byte_size ... ok
[INFO] [stdout] test mem::tests::add::should_return_msg_to_large_for_store_err ... ok
[INFO] [stdout] test mem::tests::clear_counts::should_clear_msgs_burned_count ... ok
[INFO] [stdout] test mem::tests::clear_counts::should_clear_msgs_inserted_count ... ok
[INFO] [stdout] test mem::tests::del::should_decrease_byte_size ... ok
[INFO] [stdout] test mem::tests::del::should_increase_bytes_deleted_count ... ok
[INFO] [stdout] test mem::tests::del::should_clear_deleted_count ... ok
[INFO] [stdout] test mem::tests::del::should_reset_bytes_deleted_count_and_add_diff ... ok
[INFO] [stdout] test mem::tests::delete_group_defaults::should_update_existing_group ... ok
[INFO] [stdout] test mem::tests::delete_group_defaults::should_update_existing_group ... ok
[INFO] [stdout] test mem::tests::get::should_return_oldest_msg_in_group ... ok
[INFO] [stdout] test mem::tests::del::should_remove_empty_group ... ok
[INFO] [stdout] test mem::tests::get::should_return_oldest_msg ... ok
[INFO] [stdout] test mem::tests::update_group_defaults::should_update_existing_group ... ok
[INFO] [stdout] test mem::tests::get::should_return_highest_pri_msg ... ok
[INFO] [stdout] test mem::tests::update_group_defaults::should_prune_group_after_update ... ok
[INFO] [stdout] test mem::tests::update_group_defaults::should_update_store_config ... ok
[INFO] [stdout] test mem::tests::update_store_defaults::should_update_store_config ... ok
[INFO] [stderr]    Doc-tests msg-store
[INFO] [stdout] test mem::tests::uuid::should_convert_a_str_to_uuid ... ok
[INFO] [stdout] test mem::tests::get::should_return_msg ... ok
[INFO] [stdout] test mem::tests::update_store_defaults::should_prune_store_after_update ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test src/store.rs - store::Store<Db>::add (line 372) ... ok
[INFO] [stdout] test src/store.rs - store::Store<Db>::get (line 479) ... ok
[INFO] [stdout] test src/store.rs - store::Store<Db>::del (line 426) ... ok
[INFO] [stdout] test src/store.rs - store::Store<Db>::update_group_defaults (line 542) ... ok
[INFO] [stdout] test src/store.rs - store::Store<Db>::delete_group_defaults (line 572) ... ok
[INFO] [stdout] test src/store.rs - store::Store<Db>::update_store_defaults (line 613) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.76s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "34072542e502c3c9509a0900e97339575695dead786bf4703bbf56f5800e164a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "34072542e502c3c9509a0900e97339575695dead786bf4703bbf56f5800e164a", kill_on_drop: false }`
[INFO] [stdout] 34072542e502c3c9509a0900e97339575695dead786bf4703bbf56f5800e164a