[INFO] cloning repository https://github.com/IgrisBRC/mini_redis
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/IgrisBRC/mini_redis" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FIgrisBRC%2Fmini_redis", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FIgrisBRC%2Fmini_redis'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 867167c2c061cd5ffdfda44c6214edaddc35e607
[INFO] testing IgrisBRC/mini_redis against 1.97.0-beta.6 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FIgrisBRC%2Fmini_redis" "/workspace/builds/worker-2-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/IgrisBRC/mini_redis
[INFO] finished tweaking git repo https://github.com/IgrisBRC/mini_redis
[INFO] tweaked toml for git repo https://github.com/IgrisBRC/mini_redis written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/IgrisBRC/mini_redis on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/IgrisBRC/mini_redis 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" "+1.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded bytecheck_derive v0.8.2
[INFO] [stderr]   Downloaded rkyv_derive v0.8.15
[INFO] [stderr]   Downloaded bytecheck v0.8.2
[INFO] [stderr]   Downloaded rkyv v0.8.15
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752
[INFO] running `Command { std: "docker" "start" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling simdutf8 v0.1.5
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling ptr_meta_derive v0.3.1
[INFO] [stderr]    Compiling bytecheck_derive v0.8.2
[INFO] [stderr]    Compiling munge_macro v0.4.7
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling rkyv_derive v0.8.15
[INFO] [stderr]    Compiling ptr_meta v0.3.1
[INFO] [stderr]    Compiling rancor v0.1.1
[INFO] [stderr]    Compiling bytecheck v0.8.2
[INFO] [stderr]    Compiling rend v0.5.3
[INFO] [stderr]    Compiling munge v0.4.7
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling ctrlc v3.5.2
[INFO] [stderr]    Compiling rkyv v0.8.15
[INFO] [stderr]    Compiling heaven v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.94s
[INFO] running `Command { std: "docker" "inspect" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling heaven v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: function `parse_bulk_string` is never used
[INFO] [stdout]   --> tests/integration_test.rs:65:4
[INFO] [stdout]    |
[INFO] [stdout] 65 | fn parse_bulk_string(response: &str) -> Option<String> {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `parse_simple_string` is never used
[INFO] [stdout]   --> tests/integration_test.rs:84:4
[INFO] [stdout]    |
[INFO] [stdout] 84 | fn parse_simple_string(response: &str) -> Option<String> {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `sub_id`
[INFO] [stdout]    --> tests/stress_test.rs:326:15
[INFO] [stdout]     |
[INFO] [stdout] 326 |         .map(|sub_id| {
[INFO] [stdout]     |               ^^^^^^ help: if this is intentional, prefix it with an underscore: `_sub_id`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.08s
[INFO] running `Command { std: "docker" "inspect" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: function `parse_bulk_string` is never used
[INFO] [stderr]   --> tests/integration_test.rs:65:4
[INFO] [stderr]    |
[INFO] [stderr] 65 | fn parse_bulk_string(response: &str) -> Option<String> {
[INFO] [stderr]    |    ^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `parse_simple_string` is never used
[INFO] [stderr]   --> tests/integration_test.rs:84:4
[INFO] [stderr]    |
[INFO] [stderr] 84 | fn parse_simple_string(response: &str) -> Option<String> {
[INFO] [stderr]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `sub_id`
[INFO] [stderr]    --> tests/stress_test.rs:326:15
[INFO] [stderr]     |
[INFO] [stderr] 326 |         .map(|sub_id| {
[INFO] [stderr]     |               ^^^^^^ help: if this is intentional, prefix it with an underscore: `_sub_id`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `heaven` (test "integration_test") generated 2 warnings
[INFO] [stderr] warning: `heaven` (test "stress_test") generated 1 warning (run `cargo fix --test "stress_test" -p heaven` to apply 1 suggestion)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/heaven-4f23f4bbbf880b57)
[INFO] [stdout] 
[INFO] [stdout] running 67 tests
[INFO] [stdout] test tests::soul_test::append_wrong_type_returns_error ... ok
[INFO] [stdout] test tests::soul_test::append_to_missing_key_creates_it ... ok
[INFO] [stdout] test tests::soul_test::decr_existing_integer ... ok
[INFO] [stdout] test tests::soul_test::decr_missing_key_starts_at_minus_one ... ok
[INFO] [stdout] test tests::soul_test::del_existing_keys_returns_count ... ok
[INFO] [stdout] test tests::soul_test::del_removes_key ... ok
[INFO] [stdout] test tests::soul_test::exists_counts_duplicate_keys_multiple_times ... ok
[INFO] [stdout] test tests::soul_test::decr_non_integer_returns_error ... ok
[INFO] [stdout] test tests::soul_test::append_to_existing_key_concatenates ... ok
[INFO] [stdout] test tests::soul_test::exists_returns_count_of_present_keys ... ok
[INFO] [stdout] test tests::soul_test::expire_sets_expiry_on_key ... ok
[INFO] [stdout] test tests::soul_test::expire_missing_key_returns_zero ... ok
[INFO] [stdout] test tests::soul_test::get_missing_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::get_wrong_type_returns_error ... ok
[INFO] [stdout] test tests::soul_test::hdel_removes_fields_and_returns_count ... ok
[INFO] [stdout] test tests::soul_test::hlen_returns_number_of_fields ... ok
[INFO] [stdout] test tests::soul_test::get_expired_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::hmget_returns_values_in_order_with_nones_for_missing ... ok
[INFO] [stdout] test tests::soul_test::hget_missing_field_returns_none ... ok
[INFO] [stdout] test tests::soul_test::hset_creates_fields_and_returns_added_count ... ok
[INFO] [stdout] test tests::soul_test::hset_update_existing_field_does_not_increment_count ... ok
[INFO] [stdout] test tests::soul_test::hgetall_returns_flat_interleaved_field_value_list ... ok
[INFO] [stdout] test tests::soul_test::incr_existing_integer ... ok
[INFO] [stdout] test tests::soul_test::incr_missing_key_starts_at_one ... ok
[INFO] [stdout] test tests::soul_test::incr_non_integer_returns_error ... ok
[INFO] [stdout] test tests::soul_test::lindex_negative_index ... ok
[INFO] [stdout] test tests::soul_test::lindex_out_of_range_returns_none ... ok
[INFO] [stdout] test tests::soul_test::hget_missing_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::lindex_valid_index ... ok
[INFO] [stdout] test tests::soul_test::hexists_missing_field_returns_zero ... ok
[INFO] [stdout] test tests::soul_test::hexists_present_field_returns_one ... ok
[INFO] [stdout] test tests::soul_test::llen_missing_key_is_zero ... ok
[INFO] [stdout] test tests::soul_test::lpop_empty_list_key_deleted ... ok
[INFO] [stdout] test tests::soul_test::lpop_removes_and_returns_head ... ok
[INFO] [stdout] test tests::soul_test::lpop_m_pops_n_elements ... ok
[INFO] [stdout] test tests::soul_test::lrange_full_range ... ok
[INFO] [stdout] test tests::soul_test::lpop_missing_key_returns_none ... ok
[INFO] [stdout] test tests::soul_test::lpush_creates_list_and_prepends ... ok
[INFO] [stdout] test tests::soul_test::lrange_negative_indices ... ok
[INFO] [stdout] test tests::soul_test::lrange_out_of_bounds_returns_empty ... ok
[INFO] [stdout] test tests::soul_test::lrem_count_zero_removes_all ... ok
[INFO] [stdout] test tests::soul_test::lrem_negative_count_removes_from_tail ... ok
[INFO] [stdout] test tests::soul_test::lset_out_of_range_returns_error ... ok
[INFO] [stdout] test tests::soul_test::lset_replaces_element ... ok
[INFO] [stdout] test tests::soul_test::lrem_removes_from_head ... ok
[INFO] [stdout] test tests::soul_test::rpop_m_pops_n_elements_from_tail ... ok
[INFO] [stdout] test tests::soul_test::rpop_removes_and_returns_tail ... ok
[INFO] [stdout] test tests::soul_test::sadd_adds_new_members_returns_count ... ok
[INFO] [stdout] test tests::soul_test::sadd_duplicate_not_counted ... ok
[INFO] [stdout] test tests::soul_test::set_then_get_returns_value ... ok
[INFO] [stdout] test tests::soul_test::sismember_absent_returns_zero ... ok
[INFO] [stdout] test tests::soul_test::rpush_appends_in_order ... ok
[INFO] [stdout] test tests::soul_test::sismember_present_returns_one ... ok
[INFO] [stdout] test tests::soul_test::smembers_returns_all_members ... ok
[INFO] [stdout] test tests::soul_test::srem_removes_members_returns_count ... ok
[INFO] [stdout] test tests::soul_test::strlen_existing_string ... ok
[INFO] [stdout] test tests::soul_test::strlen_missing_key_is_zero ... ok
[INFO] [stdout] test tests::soul_test::strlen_wrong_type_returns_error ... ok
[INFO] [stdout] test tests::soul_test::ttl_missing_key_returns_minus_two ... ok
[INFO] [stdout] test tests::soul_test::ttl_returns_remaining_seconds ... ok
[INFO] [stdout] test tests::pubsub_test::test_ping_in_subscriber_mode ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_subscribe_multiple_channels ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_publish_no_subscribers_returns_zero ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_unsubscribe_all ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_subscriber_mode_rejects_regular_commands ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_subscribe_receives_published_message ... FAILED
[INFO] [stdout] test tests::pubsub_test::test_multiple_subscribers_all_receive_message has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752", kill_on_drop: false }`
[INFO] [stdout] f065373a0514428ad6b27d7c17fdb29c4c977089151ee531d6a58867804b3752
