[INFO] fetching crate rainier-middleware 2.0.0...
[INFO] testing rainier-middleware-2.0.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rainier-middleware 2.0.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate rainier-middleware 2.0.0
[INFO] finished tweaking crates.io crate rainier-middleware 2.0.0
[INFO] tweaked toml for crates.io crate rainier-middleware 2.0.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rainier-middleware 2.0.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rainier-middleware 2.0.0 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde
[INFO] running `Command { std: "docker" "start" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", 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" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", 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" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling http v1.4.2
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling futures-core v0.3.33
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling http-body v1.1.0
[INFO] [stderr]    Compiling http-body-util v0.1.4
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling async-trait v0.1.91
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling rainier-support v2.0.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling rainier-container v2.0.0
[INFO] [stderr]    Compiling rainier-http v2.0.0
[INFO] [stderr]    Compiling rainier-middleware v2.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 31.44s
[INFO] running `Command { std: "docker" "inspect" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", 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" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling futures-sink v0.3.33
[INFO] [stderr]    Compiling futures-task v0.3.33
[INFO] [stderr]    Compiling futures-util v0.3.33
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.7.1
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rainier-container v2.0.0
[INFO] [stderr]    Compiling rainier-middleware v2.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 21.43s
[INFO] running `Command { std: "docker" "inspect" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", 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" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rainier-middleware/86ee13ec1687670f/out/rainier_middleware-86ee13ec1687670f)
[INFO] [stdout] 
[INFO] [stdout] running 92 tests
[INFO] [stdout] test builtin::tests::a_restricted_origin_response_varies_on_origin ... ok
[INFO] [stdout] test builtin::tests::a_custom_key_counts_what_was_submitted ... ok
[INFO] [stdout] test builtin::tests::an_unreachable_store_fails_open ... ok
[INFO] [stdout] test builtin::tests::cors_answers_a_preflight_without_reaching_the_route ... ok
[INFO] [stdout] test builtin::tests::a_memory_backed_throttle_says_it_is_not_shared ... ok
[INFO] [stdout] test builtin::tests::credentials_never_pair_with_a_wildcard_origin ... ok
[INFO] [stdout] test builtin::tests::cors_decorates_an_ordinary_response ... ok
[INFO] [stdout] test builtin::tests::a_throttled_response_is_a_framework_error_body ... ok
[INFO] [stdout] test builtin::tests::throttling_counts_each_client_separately ... ok
[INFO] [stdout] test builtin::tests::cors_origin_matching ... ok
[INFO] [stdout] test builtin::tests::throttling_allows_up_to_the_limit_then_rejects ... ok
[INFO] [stdout] test builtin::tests::a_request_with_nothing_to_key_on_is_not_counted ... ok
[INFO] [stdout] test builtin::tests::a_name_namespaces_the_key ... ok
[INFO] [stdout] test builtin::tests::trim_strings_leaves_passwords_alone ... ok
[INFO] [stdout] test builtin::tests::add_headers_decorates_the_response ... ok
[INFO] [stdout] test builtin::tests::trim_strings_trims_recursively ... ok
[INFO] [stdout] test compression::tests::a_response_that_is_already_encoded_is_not_encoded_twice ... ok
[INFO] [stdout] test builtin::tests::empty_strings_become_null ... ok
[INFO] [stdout] test builtin::tests::throttling_keys_authenticated_requests_by_token ... ok
[INFO] [stdout] test compression::tests::a_small_body_is_left_alone ... ok
[INFO] [stdout] test builtin::tests::trimming_then_nulling_turns_blank_into_null ... ok
[INFO] [stdout] test compression::tests::a_client_that_asked_for_nothing_gets_the_original ... ok
[INFO] [stdout] test compression::tests::a_json_body_is_gzipped_and_round_trips ... ok
[INFO] [stdout] test compression::tests::a_streaming_body_is_never_buffered ... ok
[INFO] [stdout] test compression::tests::an_image_is_left_alone ... ok
[INFO] [stdout] test compression::tests::only_the_textual_types_are_compressed ... ok
[INFO] [stdout] test compression::tests::an_encoding_refused_by_q_zero_is_not_used ... ok
[INFO] [stdout] test compression::tests::the_best_offer_wins ... ok
[INFO] [stdout] test method_override::tests::a_get_is_never_rewritten ... ok
[INFO] [stdout] test method_override::tests::a_request_with_nothing_to_say_is_left_alone ... ok
[INFO] [stdout] test compression::tests::the_status_and_other_headers_survive ... ok
[INFO] [stdout] test method_override::tests::a_post_cannot_become_a_get ... ok
[INFO] [stdout] test method_override::tests::a_post_with_the_field_becomes_that_method ... ok
[INFO] [stdout] test compression::tests::deflate_is_used_when_it_is_the_only_thing_offered ... ok
[INFO] [stdout] test method_override::tests::nonsense_is_ignored_rather_than_refused ... ok
[INFO] [stdout] test pipeline::tests::middleware_can_rewrite_the_request_and_the_response ... ok
[INFO] [stdout] test method_override::tests::the_header_is_ignored_until_it_is_trusted ... ok
[INFO] [stdout] test method_override::tests::the_spelling_does_not_have_to_be_shouted ... ok
[INFO] [stdout] test pipeline::tests::a_ready_pipeline_can_be_run_repeatedly ... ok
[INFO] [stdout] test pipeline::tests::an_empty_pipeline_reaches_the_destination ... ok
[INFO] [stdout] test proxy::tests::a_client_cannot_forge_an_address_by_prepending_one ... ok
[INFO] [stdout] test proxy::tests::a_prefix_matches_its_network ... ok
[INFO] [stdout] test proxy::tests::a_bare_address_is_a_single_host ... ok
[INFO] [stdout] test pipeline::tests::middleware_wraps_the_destination_in_order ... ok
[INFO] [stdout] test proxy::tests::a_chain_of_only_trusted_hops_falls_back_to_the_peer ... ok
[INFO] [stdout] test pipeline::tests::short_circuiting_skips_the_rest_and_the_handler ... ok
[INFO] [stdout] test proxy::tests::a_prefix_that_is_not_a_whole_byte_still_works ... ok
[INFO] [stdout] test proxy::tests::a_request_with_no_recorded_address_passes_through ... ok
[INFO] [stdout] test proxy::tests::a_trusted_peer_yields_the_client ... ok
[INFO] [stdout] test proxy::tests::an_alternative_header_can_be_read ... ok
[INFO] [stdout] test pipeline::tests::next_reports_how_much_is_left ... ok
[INFO] [stdout] test proxy::tests::ipv6_works_too ... ok
[INFO] [stdout] test proxy::tests::an_untrusted_peer_is_believed_over_its_own_header ... ok
[INFO] [stdout] test proxy::tests::families_do_not_cross ... ok
[INFO] [stdout] test proxy::tests::rubbish_entries_are_skipped ... ok
[INFO] [stdout] test proxy::tests::no_header_leaves_the_peer_alone ... ok
[INFO] [stdout] test proxy::tests::rubbish_does_not_parse ... ok
[INFO] [stdout] test proxy::tests::the_middleware_rewrites_the_recorded_address ... ok
[INFO] [stdout] test proxy::tests::trusted_hops_are_walked_past_from_the_right ... ok
[INFO] [stdout] test rate_limit::tests::clearing_restores_the_whole_allowance ... ok
[INFO] [stdout] test rate_limit::tests::hits_count_up_within_a_window ... ok
[INFO] [stdout] test rate_limit::tests::it_says_it_is_not_shared ... ok
[INFO] [stdout] test proxy::tests::trusting_everything_believes_the_chain ... ok
[INFO] [stdout] test proxy::tests::trusting_everything_takes_the_rightmost_entry ... ok
[INFO] [stdout] test proxy::tests::trusting_nobody_ignores_the_header ... ok
[INFO] [stdout] test registry::tests::a_tuple_registers_several_at_once_in_order ... ok
[INFO] [stdout] test rate_limit::tests::keys_are_counted_separately ... ok
[INFO] [stdout] test registry::tests::a_failing_deferred_global_stops_the_boot ... ok
[INFO] [stdout] test registry::tests::a_whole_stack_can_be_registered_globally ... ok
[INFO] [stdout] test registry::tests::the_global_stack_keeps_its_order ... ok
[INFO] [stdout] test registry::tests::the_labels_are_readable_without_a_container ... ok
[INFO] [stdout] test stack::tests::a_deferred_stage_is_built_at_resolution_not_declaration ... ok
[INFO] [stdout] test stack::tests::a_deferred_stage_that_fails_names_its_type ... ok
[INFO] [stdout] test stack::tests::a_shared_instance_is_not_cloned_per_route ... ok
[INFO] [stdout] test stack::tests::a_stack_keeps_the_order_it_was_built_in ... ok
[INFO] [stdout] test stack::tests::a_stack_converts_to_itself ... ok
[INFO] [stdout] test stack::tests::nesting_a_stack_flattens_it_in_place ... ok
[INFO] [stdout] test stack::tests::a_deferred_stage_labels_itself_before_it_is_built ... ok
[INFO] [stdout] test stack::tests::one_middleware_converts_to_a_stack_of_one ... ok
[INFO] [stdout] test stack::tests::prepending_puts_the_group_outside_the_routes_own ... ok
[INFO] [stdout] test stack::tests::resolved_reports_the_missing_binding_by_type ... ok
[INFO] [stdout] test stack::tests::resolved_builds_from_a_binding ... ok
[INFO] [stdout] test timeout::tests::a_handler_that_finishes_in_time_is_untouched ... ok
[INFO] [stdout] test stack::tests::a_tuple_converts_in_order ... ok
[INFO] [stdout] test rate_limit::tests::the_reset_time_counts_down ... ok
[INFO] [stdout] test timeout::tests::a_handler_that_overruns_gets_408 ... ok
[INFO] [stdout] test timeout::tests::the_message_says_how_long_it_waited ... ok
[INFO] [stdout] test builtin::tests::the_limit_is_enforced_and_the_window_resets ... ok
[INFO] [stdout] test rate_limit::tests::a_window_expires_and_the_allowance_comes_back ... ok
[INFO] [stdout] test rate_limit::tests::dead_keys_are_evicted_rather_than_accumulating ... ok
[INFO] [stdout] test rate_limit::tests::an_expired_window_reads_as_nothing_rather_than_as_a_stale_count ... ok
[INFO] [stdout] test timeout::tests::the_overrunning_handler_is_actually_dropped ... ok
[INFO] [stderr]    Doc-tests rainier_middleware
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 92 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.77s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test src/builtin.rs - builtin::ThrottleRequests (line 309) ... ignored
[INFO] [stdout] test src/builtin.rs - builtin::ThrottleRequests::keyed_by (line 410) ... ignored
[INFO] [stdout] test src/compression.rs - compression (line 3) ... ignored
[INFO] [stdout] test src/method_override.rs - method_override (line 3) ... ignored
[INFO] [stdout] test src/pipeline.rs - pipeline (line 9) ... ok
[INFO] [stdout] test src/stack.rs - stack (line 61) ... ignored
[INFO] [stdout] test src/stack.rs - stack (line 10) ... ok
[INFO] [stdout] test src/stack.rs - stack::MiddlewareStack::resolved (line 163) ... ignored
[INFO] [stdout] test src/timeout.rs - timeout (line 3) ... ignored
[INFO] [stdout] test src/stack.rs - stack::IntoMiddlewareStack (line 240) ... ok
[INFO] [stdout] test src/pipeline.rs - pipeline::Pipeline (line 159) ... ok
[INFO] [stdout] test src/registry.rs - registry::MiddlewareRegistry::global (line 52) ... ok
[INFO] [stdout] test src/registry.rs - registry (line 15) ... ok
[INFO] [stdout] test src/proxy.rs - proxy::TrustProxies (line 126) ... ok
[INFO] [stdout] test src/stack.rs - stack (line 29) ... ok
[INFO] [stdout] test src/lib.rs - (line 6) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out; finished in 1.65s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde", kill_on_drop: false }`
[INFO] [stdout] 069b3c3e957ac8443aa6ec0ecab4613a545a5e1a19cbd58c8bb976dcb5872bde
