[INFO] fetching crate http-test-server 2.1.0...
[INFO] testing http-test-server-2.1.0 against try#ecabaf78506b7a4668d42dc20268c086b93f0fad for pr-87041
[INFO] extracting crate http-test-server 2.1.0 into /workspace/builds/worker-10/source
[INFO] validating manifest of crates.io crate http-test-server 2.1.0 on toolchain ecabaf78506b7a4668d42dc20268c086b93f0fad
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate http-test-server 2.1.0
[INFO] finished tweaking crates.io crate http-test-server 2.1.0
[INFO] tweaked toml for crates.io crate http-test-server 2.1.0 written to /workspace/builds/worker-10/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a10fcf7058d651112dc5679df1e0a6410f136e22eabd653524459f66b17cebbf
[INFO] running `Command { std: "docker" "start" "-a" "a10fcf7058d651112dc5679df1e0a6410f136e22eabd653524459f66b17cebbf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a10fcf7058d651112dc5679df1e0a6410f136e22eabd653524459f66b17cebbf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a10fcf7058d651112dc5679df1e0a6410f136e22eabd653524459f66b17cebbf", kill_on_drop: false }`
[INFO] [stdout] a10fcf7058d651112dc5679df1e0a6410f136e22eabd653524459f66b17cebbf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7e9a0cf67b61817e5bc6cbbfcb44d8fb350e444f05983faf24b3f26aac86ecd1
[INFO] running `Command { std: "docker" "start" "-a" "7e9a0cf67b61817e5bc6cbbfcb44d8fb350e444f05983faf24b3f26aac86ecd1", kill_on_drop: false }`
[INFO] [stderr]    Compiling http-test-server v2.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.31s
[INFO] running `Command { std: "docker" "inspect" "7e9a0cf67b61817e5bc6cbbfcb44d8fb350e444f05983faf24b3f26aac86ecd1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7e9a0cf67b61817e5bc6cbbfcb44d8fb350e444f05983faf24b3f26aac86ecd1", kill_on_drop: false }`
[INFO] [stdout] 7e9a0cf67b61817e5bc6cbbfcb44d8fb350e444f05983faf24b3f26aac86ecd1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a288c12515aacff1088d8c1b30f7ad1062e1c903d76aa0c0a887ad5834696920
[INFO] running `Command { std: "docker" "start" "-a" "a288c12515aacff1088d8c1b30f7ad1062e1c903d76aa0c0a887ad5834696920", kill_on_drop: false }`
[INFO] [stderr]    Compiling http-test-server v2.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 3.27s
[INFO] running `Command { std: "docker" "inspect" "a288c12515aacff1088d8c1b30f7ad1062e1c903d76aa0c0a887ad5834696920", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a288c12515aacff1088d8c1b30f7ad1062e1c903d76aa0c0a887ad5834696920", kill_on_drop: false }`
[INFO] [stdout] a288c12515aacff1088d8c1b30f7ad1062e1c903d76aa0c0a887ad5834696920
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 69493c726ff6c17c83699d5b80313ad50f310ce633470eac90c707349f0950b4
[INFO] running `Command { std: "docker" "start" "-a" "69493c726ff6c17c83699d5b80313ad50f310ce633470eac90c707349f0950b4", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stdout] running 52 tests
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/http_test_server-1bb95337a3151f4c)
[INFO] [stdout] test resource::tests::should_append_headers ... ok
[INFO] [stdout] test resource::tests::should_send_data_with_line_break ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_query_params_defined_through_method ... ok
[INFO] [stdout] test resource::tests::should_build_response_using_body_fn ... ok
[INFO] [stdout] test resource::tests::should_return_number_of_connecteds_users ... ok
[INFO] [stdout] test resource::tests::should_decrease_count_when_receiver_dropped ... ok
[INFO] [stdout] test resource::tests::should_allows_custom_status ... ok
[INFO] [stdout] test resource::tests::should_not_match_uri_when_one_query_param_is_wrong ... ok
[INFO] [stdout] test resource::tests::should_not_match_uri_with_wrong_query_parameter ... ok
[INFO] [stdout] test resource::tests::clones_should_share_same_state ... ok
[INFO] [stdout] test resource::tests::should_notify_data ... ok
[INFO] [stdout] test resource::tests::should_close_connections ... ok
[INFO] [stdout] test resource::tests::should_build_response_with_wildcard_query_parameters ... ok
[INFO] [stdout] test resource::tests::should_build_response_with_query_parameters ... ok
[INFO] [stdout] test resource::tests::should_build_response_with_body ... ok
[INFO] [stdout] test resource::tests::should_not_match_uri_with_path_params_when_uri_does_not_match ... ok
[INFO] [stdout] test resource::tests::should_set_delay ... ok
[INFO] [stdout] test tests::server_should_use_random_port ... ok
[INFO] [stdout] test tests::should_return_configured_status_for_resource_resource ... ok
[INFO] [stdout] test tests::returns_404_when_requested_enexistent_resource ... ok
[INFO] [stdout] test tests::should_return_resource_body ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_query_params_in_different_order ... ok
[INFO] [stdout] test resource::tests::should_convert_to_response_with_body ... ok
[INFO] [stdout] test resource::tests::should_set_as_stream ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_wildcard_query_params ... ok
[INFO] [stdout] test tests::should_create_resource ... ok
[INFO] [stdout] test tests::should_allow_multiple_methods_for_same_uri ... ok
[INFO] [stdout] test resource::tests::should_convert_to_response_string ... ok
[INFO] [stdout] test resource::tests::should_build_response_with_path_parameters ... ok
[INFO] [stdout] test resource::tests::should_not_match_uri_when_uri_does_not_match ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_wildcard_query_params_defined_through_method ... ok
[INFO] [stdout] test resource::tests::should_increment_request_count ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_multiple_query_params ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_query_params ... ok
[INFO] [stdout] test resource::tests::should_overwrite_custom_status_with_status ... ok
[INFO] [stdout] test resource::tests::should_add_headers ... ok
[INFO] [stdout] test resource::tests::should_match_uri ... ok
[INFO] [stdout] test resource::tests::should_match_uri_with_path_params ... ok
[INFO] [stdout] test resource::tests::should_build_response ... ok
[INFO] [stdout] test tests::should_close_connection ... ok
[INFO] [stdout] test resource::tests::should_fail_when_trying_to_define_body_after_defining_body_fn - should panic ... ok
[INFO] [stdout] test resource::tests::should_fail_when_trying_to_define_body_fn_after_defining_body - should panic ... ok
[INFO] [stdout] test tests::should_return_requests_metadata ... ok
[INFO] [stdout] test tests::should_return_resource_body_with_params ... ok
[INFO] [stdout] test tests::server_should_close_connection_when_dropped ... FAILED
[INFO] [stdout] test tests::should_listen_to_defined_method ... ok
[INFO] [stdout] test tests::should_increment_request_count ... ok
[INFO] [stdout] test tests::should_work_with_regex_uri ... ok
[INFO] [stdout] test tests::should_return_405_when_method_not_defined ... ok
[INFO] [stdout] test tests::should_expose_stream ... ok
[INFO] [stdout] test tests::should_delay_response ... FAILED
[INFO] [stdout] test tests::should_close_client_connections has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "69493c726ff6c17c83699d5b80313ad50f310ce633470eac90c707349f0950b4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "69493c726ff6c17c83699d5b80313ad50f310ce633470eac90c707349f0950b4", kill_on_drop: false }`
[INFO] [stdout] 69493c726ff6c17c83699d5b80313ad50f310ce633470eac90c707349f0950b4