[INFO] cloning repository https://github.com/xiemulei/simple-redis
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/xiemulei/simple-redis" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxiemulei%2Fsimple-redis", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxiemulei%2Fsimple-redis'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 917923b288b55b65ca431e2388a4d7f1c2f2c221
[INFO] testing xiemulei/simple-redis against try#8da20828b6ea601dfd8e2448cb50ace88876bd78 for pr-150933
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxiemulei%2Fsimple-redis" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/xiemulei/simple-redis
[INFO] finished tweaking git repo https://github.com/xiemulei/simple-redis
[INFO] tweaked toml for git repo https://github.com/xiemulei/simple-redis written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/xiemulei/simple-redis on toolchain 8da20828b6ea601dfd8e2448cb50ace88876bd78
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/xiemulei/simple-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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "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
[ERROR] error running command: no output for 300 seconds
[INFO] testing xiemulei/simple-redis against try#8da20828b6ea601dfd8e2448cb50ace88876bd78 for pr-150933
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxiemulei%2Fsimple-redis" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/xiemulei/simple-redis
[INFO] finished tweaking git repo https://github.com/xiemulei/simple-redis
[INFO] tweaked toml for git repo https://github.com/xiemulei/simple-redis written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/xiemulei/simple-redis on toolchain 8da20828b6ea601dfd8e2448cb50ace88876bd78
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/xiemulei/simple-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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "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] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded enum_dispatch v0.3.13
[INFO] [stderr]   Downloaded thiserror-impl v2.0.7
[INFO] [stderr]   Downloaded thiserror v2.0.7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 61879acecaecfc21e3cd74f5440771c48164015224578e0028f575b948432406
[INFO] running `Command { std: "docker" "start" "-a" "61879acecaecfc21e3cd74f5440771c48164015224578e0028f575b948432406", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "61879acecaecfc21e3cd74f5440771c48164015224578e0028f575b948432406", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "61879acecaecfc21e3cd74f5440771c48164015224578e0028f575b948432406", kill_on_drop: false }`
[INFO] [stdout] 61879acecaecfc21e3cd74f5440771c48164015224578e0028f575b948432406
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 01a68828a43c42b15336f8d2a616b35feb31900d1b433f1863c56802dc9a0f83
[INFO] running `Command { std: "docker" "start" "-a" "01a68828a43c42b15336f8d2a616b35feb31900d1b433f1863c56802dc9a0f83", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.169
[INFO] [stderr]    Compiling pin-project-lite v0.2.15
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling anyhow v1.0.94
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling thiserror v2.0.7
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling matchers v0.1.0
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling bytes v1.9.0
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling syn v2.0.90
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling socket2 v0.5.8
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling tokio-macros v2.4.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling thiserror-impl v2.0.7
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling tokio v1.42.0
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling tokio-util v0.7.13
[INFO] [stderr]    Compiling simple-redis v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.11s
[INFO] running `Command { std: "docker" "inspect" "01a68828a43c42b15336f8d2a616b35feb31900d1b433f1863c56802dc9a0f83", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "01a68828a43c42b15336f8d2a616b35feb31900d1b433f1863c56802dc9a0f83", kill_on_drop: false }`
[INFO] [stdout] 01a68828a43c42b15336f8d2a616b35feb31900d1b433f1863c56802dc9a0f83
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 48d5f9101b6c17d74102322fe2c329dfc431f010293adcb953160aee981d88ee
[INFO] running `Command { std: "docker" "start" "-a" "48d5f9101b6c17d74102322fe2c329dfc431f010293adcb953160aee981d88ee", kill_on_drop: false }`
[INFO] [stderr]    Compiling simple-redis v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.55s
[INFO] running `Command { std: "docker" "inspect" "48d5f9101b6c17d74102322fe2c329dfc431f010293adcb953160aee981d88ee", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48d5f9101b6c17d74102322fe2c329dfc431f010293adcb953160aee981d88ee", kill_on_drop: false }`
[INFO] [stdout] 48d5f9101b6c17d74102322fe2c329dfc431f010293adcb953160aee981d88ee
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f0cca3dda1b0a3ae7795b84970bdf2189d0fdcac95dba47032600a77e2a8bf6d
[INFO] running `Command { std: "docker" "start" "-a" "f0cca3dda1b0a3ae7795b84970bdf2189d0fdcac95dba47032600a77e2a8bf6d", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/simple_redis-1c5858ddcf332304)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test cmd::hmap::tests::test_hgetall_from_resp_array ... ok
[INFO] [stdout] test cmd::hmap::tests::test_hset_from_resp_array ... ok
[INFO] [stdout] test cmd::map::tests::test_set_from_resp_array ... ok
[INFO] [stdout] test cmd::map::tests::test_get_from_resp_array ... ok
[INFO] [stdout] test resp::array::tests::test_array_decode ... ok
[INFO] [stdout] test cmd::map::tests::test_set_get_command ... ok
[INFO] [stdout] test resp::array::tests::test_null_array_decode ... ok
[INFO] [stdout] test resp::bool::tests::test_boolean_decode ... ok
[INFO] [stdout] test resp::array::tests::test_array_encode ... ok
[INFO] [stdout] test resp::array::tests::test_null_array_encode ... ok
[INFO] [stdout] test resp::bulk_string::tests::test_bulk_string_decode ... ok
[INFO] [stdout] test resp::bool::tests::test_boolean_encode ... ok
[INFO] [stdout] test resp::bulk_string::tests::test_bulk_string_encode ... ok
[INFO] [stdout] test resp::bulk_string::tests::test_null_bulk_string_encode ... ok
[INFO] [stdout] test resp::bulk_string::tests::test_null_bulk_string_decode ... ok
[INFO] [stdout] test resp::double::tests::test_double_decode ... ok
[INFO] [stdout] test resp::double::tests::test_double_encode ... ok
[INFO] [stdout] test resp::integer::tests::test_integer_encode ... ok
[INFO] [stdout] test resp::map::tests::test_map_decode ... ok
[INFO] [stdout] test resp::map::tests::test_map_encode ... ok
[INFO] [stdout] test resp::null::tests::test_null_encode ... ok
[INFO] [stdout] test resp::null::tests::test_null_decode ... ok
[INFO] [stdout] test resp::simple_error::tests::test_simple_error_decode ... ok
[INFO] [stdout] test resp::simple_error::tests::test_error_encode ... ok
[INFO] [stdout] test resp::tests::test_calc_array_length ... ok
[INFO] [stdout] test resp::simple_string::tests::test_simple_string_encode ... ok
[INFO] [stdout] test resp::simple_string::tests::test_simple_string_decode ... ok
[INFO] [stdout] test cmd::hmap::tests::test_hget_from_resp_array ... ok
[INFO] [stdout] test resp::set::tests::test_set_decode ... ok
[INFO] [stdout] test resp::set::tests::test_set_encode ... ok
[INFO] [stdout] test resp::integer::tests::test_integer_decode ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/simple_redis-fb4de8c6fb7f8d3b)
[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]    Doc-tests simple_redis
[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] running `Command { std: "docker" "inspect" "f0cca3dda1b0a3ae7795b84970bdf2189d0fdcac95dba47032600a77e2a8bf6d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f0cca3dda1b0a3ae7795b84970bdf2189d0fdcac95dba47032600a77e2a8bf6d", kill_on_drop: false }`
[INFO] [stdout] f0cca3dda1b0a3ae7795b84970bdf2189d0fdcac95dba47032600a77e2a8bf6d