[INFO] cloning repository https://github.com/ben-yu/redis-rust
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ben-yu/redis-rust" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fben-yu%2Fredis-rust", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fben-yu%2Fredis-rust'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] b5cc62dcfe364985300a91ba6ec162b526e294f5
[INFO] testing ben-yu/redis-rust against master#733b47ea4b1b86216f14ef56e49440c33933f230+rustflags=-Copt-level=3 for pr-138759
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fben-yu%2Fredis-rust" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/ben-yu/redis-rust
[INFO] finished tweaking git repo https://github.com/ben-yu/redis-rust
[INFO] tweaked toml for git repo https://github.com/ben-yu/redis-rust written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/ben-yu/redis-rust on toolchain 733b47ea4b1b86216f14ef56e49440c33933f230
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+733b47ea4b1b86216f14ef56e49440c33933f230" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/ben-yu/redis-rust 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" "+733b47ea4b1b86216f14ef56e49440c33933f230" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+733b47ea4b1b86216f14ef56e49440c33933f230" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] fd8af13f66c72fb330c605c73a9fefad3d2013a9731a25cbbbc4312659cd8700
[INFO] running `Command { std: "docker" "start" "-a" "fd8af13f66c72fb330c605c73a9fefad3d2013a9731a25cbbbc4312659cd8700", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fd8af13f66c72fb330c605c73a9fefad3d2013a9731a25cbbbc4312659cd8700", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fd8af13f66c72fb330c605c73a9fefad3d2013a9731a25cbbbc4312659cd8700", kill_on_drop: false }`
[INFO] [stdout] fd8af13f66c72fb330c605c73a9fefad3d2013a9731a25cbbbc4312659cd8700
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+733b47ea4b1b86216f14ef56e49440c33933f230" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 74ada4f4ea4c2d3d15359032c9ac2d25c15d0ad2bf45131b3fc30cb93113fa04
[INFO] running `Command { std: "docker" "start" "-a" "74ada4f4ea4c2d3d15359032c9ac2d25c15d0ad2bf45131b3fc30cb93113fa04", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.86
[INFO] [stderr]    Compiling anyhow v1.0.86
[INFO] [stderr]    Compiling bytes v1.6.0
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling num_cpus v1.16.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling socket2 v0.5.7
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling mio v0.8.11
[INFO] [stderr]    Compiling threadpool v1.8.1
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling quote v1.0.36
[INFO] [stderr]    Compiling syn v2.0.69
[INFO] [stderr]    Compiling thiserror-impl v1.0.61
[INFO] [stderr]    Compiling tokio-macros v2.3.0
[INFO] [stderr]    Compiling tokio v1.38.0
[INFO] [stderr]    Compiling thiserror v1.0.61
[INFO] [stderr]    Compiling codecrafters-redis v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 55.67s
[INFO] running `Command { std: "docker" "inspect" "74ada4f4ea4c2d3d15359032c9ac2d25c15d0ad2bf45131b3fc30cb93113fa04", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "74ada4f4ea4c2d3d15359032c9ac2d25c15d0ad2bf45131b3fc30cb93113fa04", kill_on_drop: false }`
[INFO] [stdout] 74ada4f4ea4c2d3d15359032c9ac2d25c15d0ad2bf45131b3fc30cb93113fa04
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+733b47ea4b1b86216f14ef56e49440c33933f230" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ecdb8bd670c47ae4f741ad161781c0a3095ac4a9290063ba69306402fa876957
[INFO] running `Command { std: "docker" "start" "-a" "ecdb8bd670c47ae4f741ad161781c0a3095ac4a9290063ba69306402fa876957", kill_on_drop: false }`
[INFO] [stderr]    Compiling codecrafters-redis v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.57s
[INFO] running `Command { std: "docker" "inspect" "ecdb8bd670c47ae4f741ad161781c0a3095ac4a9290063ba69306402fa876957", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ecdb8bd670c47ae4f741ad161781c0a3095ac4a9290063ba69306402fa876957", kill_on_drop: false }`
[INFO] [stdout] ecdb8bd670c47ae4f741ad161781c0a3095ac4a9290063ba69306402fa876957
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+733b47ea4b1b86216f14ef56e49440c33933f230" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] bfe37c817609e3bb4293813f99975104d2c552713a06285bb26c0e811d794d79
[INFO] running `Command { std: "docker" "start" "-a" "bfe37c817609e3bb4293813f99975104d2c552713a06285bb26c0e811d794d79", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stdout] running 89 tests
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/codecrafters_redis-554fa14c78d89ca5)
[INFO] [stdout] test tests::test_echo_command ... ok
[INFO] [stdout] test tests::test_lpop_nonexistent_key ... ok
[INFO] [stdout] test tests::test_get_nonexistent_key ... ok
[INFO] [stdout] test tests::test_llen_nonexistent_key ... ok
[INFO] [stdout] test tests::test_llen_existing_list ... ok
[INFO] [stdout] test tests::test_blpop_immediate ... ok
[INFO] [stdout] test tests::test_llen_empty_list ... ok
[INFO] [stdout] test tests::test_llen_wrong_type ... ok
[INFO] [stdout] test tests::test_lpop_empty_list ... ok
[INFO] [stdout] test tests::test_lpop_removes_key_when_empty ... ok
[INFO] [stdout] test tests::test_lpop_with_count ... ok
[INFO] [stdout] test tests::test_parse_blpop ... ok
[INFO] [stdout] test tests::test_parse_echo ... ok
[INFO] [stdout] test tests::test_parse_echo_empty_string ... ok
[INFO] [stdout] test tests::test_parse_empty_request ... ok
[INFO] [stdout] test tests::test_parse_get ... ok
[INFO] [stdout] test tests::test_parse_invalid_command ... ok
[INFO] [stdout] test tests::test_parse_llen ... ok
[INFO] [stdout] test tests::test_parse_lpop_no_count ... ok
[INFO] [stdout] test tests::test_parse_lpop_with_count ... ok
[INFO] [stdout] test tests::test_parse_lpush_multiple_values ... ok
[INFO] [stdout] test tests::test_parse_lpush_single_value ... ok
[INFO] [stdout] test tests::test_parse_lrange ... ok
[INFO] [stdout] test tests::test_parse_lrange_negative_indices ... ok
[INFO] [stdout] test tests::test_parse_mixed_commands ... ok
[INFO] [stdout] test tests::test_parse_multiple_pings ... ok
[INFO] [stdout] test tests::test_parse_ping ... ok
[INFO] [stdout] test tests::test_parse_ping_lowercase ... ok
[INFO] [stdout] test tests::test_parse_rpush_multiple_values ... ok
[INFO] [stdout] test tests::test_parse_set_simple ... ok
[INFO] [stdout] test tests::test_parse_set_with_ex ... ok
[INFO] [stdout] test tests::test_parse_set_with_px ... ok
[INFO] [stdout] test tests::test_parse_xadd ... ok
[INFO] [stdout] test tests::test_parse_xadd_multiple_fields ... ok
[INFO] [stdout] test tests::test_parse_xrange ... ok
[INFO] [stdout] test tests::test_parse_xread ... ok
[INFO] [stdout] test tests::test_parse_xread_block ... ok
[INFO] [stdout] test tests::test_lpop_count_exceeds_length ... ok
[INFO] [stdout] test tests::test_lpop_single_element ... ok
[INFO] [stdout] test tests::test_lpop_order ... ok
[INFO] [stdout] test tests::test_llen_after_operations ... ok
[INFO] [stdout] test tests::test_lpush_new_list ... ok
[INFO] [stdout] test tests::test_lpush_reverses_order ... ok
[INFO] [stdout] test tests::test_lrange_nonexistent_key ... ok
[INFO] [stdout] test tests::test_lpush_multiple_values ... ok
[INFO] [stdout] test tests::test_lrange_basic ... ok
[INFO] [stdout] test tests::test_lrange_negative_indices ... ok
[INFO] [stdout] test tests::test_lrange_empty_range ... ok
[INFO] [stdout] test tests::test_ping_command ... ok
[INFO] [stdout] test tests::test_multiple_pings ... ok
[INFO] [stdout] test tests::test_lrange_partial ... ok
[INFO] [stdout] test tests::test_rpush_new_list ... ok
[INFO] [stdout] test tests::test_rpush_multiple_values ... ok
[INFO] [stdout] test tests::test_set_and_get_commands ... ok
[INFO] [stdout] test tests::test_lpush_prepends_to_existing ... ok
[INFO] [stdout] test tests::test_xadd_multiple_fields ... ok
[INFO] [stdout] test tests::test_xadd_full_auto_id_increments_same_millisecond ... ok
[INFO] [stdout] test tests::test_xadd_partial_auto_id_starts_at_zero ... ok
[INFO] [stdout] test tests::test_xadd_creates_stream_automatically ... ok
[INFO] [stdout] test tests::test_xadd_partial_auto_id_zero_starts_at_one ... ok
[INFO] [stdout] test tests::test_xadd_id_must_be_greater ... ok
[INFO] [stdout] test tests::test_xadd_partial_auto_id_zero_increments ... ok
[INFO] [stdout] test tests::test_xadd_with_auto_id ... ok
[INFO] [stdout] test tests::test_xadd_sequential_entries ... ok
[INFO] [stdout] test tests::test_xadd_partial_auto_id_increments ... ok
[INFO] [stdout] test tests::test_xadd_mixed_explicit_and_auto_seq ... ok
[INFO] [stdout] test tests::test_xadd_partial_auto_id_different_times ... ok
[INFO] [stdout] test tests::test_xadd_wrong_type ... ok
[INFO] [stdout] test tests::test_set_with_expiry ... ok
[INFO] [stdout] test tests::test_xadd_with_explicit_id ... ok
[INFO] [stdout] test tests::test_xadd_zero_id_not_allowed ... ok
[INFO] [stdout] test tests::test_xrange_empty_stream ... ok
[INFO] [stdout] test tests::test_xrange_multiple_fields ... ok
[INFO] [stdout] test tests::test_xrange_inclusive ... ok
[INFO] [stdout] test tests::test_xrange_minus_plus ... ok
[INFO] [stdout] test tests::test_xrange_basic ... ok
[INFO] [stdout] test tests::test_xread_dollar_nonblocking_returns_empty ... ok
[INFO] [stdout] test tests::test_xrange_no_matches ... ok
[INFO] [stdout] test tests::test_xread_dollar_sign ... ok
[INFO] [stdout] test tests::test_blpop_timeout ... ok
[INFO] [stdout] test tests::test_xrange_partial_range ... ok
[INFO] [stdout] test tests::test_xread_no_new_entries ... ok
[INFO] [stdout] test tests::test_xread_nonexistent_stream ... ok
[INFO] [stdout] test tests::test_xread_basic ... ok
[INFO] [stdout] test tests::test_xread_dollar_with_nonexistent_stream ... ok
[INFO] [stdout] test tests::test_xread_multiple_streams ... ok
[INFO] [stdout] test tests::test_xread_dollar_multiple_streams ... ok
[INFO] [stdout] test tests::test_xread_dollar_with_block_new_entries ... ok
[INFO] [stdout] test tests::test_xread_block_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 89 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.55s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "bfe37c817609e3bb4293813f99975104d2c552713a06285bb26c0e811d794d79", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bfe37c817609e3bb4293813f99975104d2c552713a06285bb26c0e811d794d79", kill_on_drop: false }`
[INFO] [stdout] bfe37c817609e3bb4293813f99975104d2c552713a06285bb26c0e811d794d79