[INFO] fetching crate ecat-middleware 7.0.0...
[INFO] testing ecat-middleware-7.0.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate ecat-middleware 7.0.0 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate ecat-middleware 7.0.0
[INFO] finished tweaking crates.io crate ecat-middleware 7.0.0
[INFO] tweaked toml for crates.io crate ecat-middleware 7.0.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate ecat-middleware 7.0.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate ecat-middleware 7.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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded ecat-tracing v7.0.0
[INFO] [stderr]   Downloaded ecat-metadata v7.0.0
[INFO] [stderr]   Downloaded redis v0.27.6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c
[INFO] running `Command { std: "docker" "start" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", 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" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", 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" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling futures-task v0.3.33
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling futures-channel v0.3.33
[INFO] [stderr]    Compiling http-body-util v0.1.4
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling prost v0.13.5
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling uuid v1.24.0
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling futures-util v0.3.33
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.7
[INFO] [stderr]    Compiling tokio-macros v2.7.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling pin-project-internal v1.1.13
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling async-trait v0.1.92
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling pin-project v1.1.13
[INFO] [stderr]    Compiling axum-core v0.4.5
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio-util v0.7.19
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-stream v0.1.19
[INFO] [stderr]    Compiling axum v0.7.9
[INFO] [stderr]    Compiling h2 v0.4.19
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling hyper v1.11.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-timeout v0.5.2
[INFO] [stderr]    Compiling axum v0.8.9
[INFO] [stderr]    Compiling tonic v0.12.3
[INFO] [stderr]    Compiling ecat-metadata v7.0.0
[INFO] [stderr]    Compiling ecat-tracing v7.0.0
[INFO] [stderr]    Compiling ecat-middleware v7.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 56.53s
[INFO] running `Command { std: "docker" "inspect" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", 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" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling ecat-middleware v7.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 4.48s
[INFO] running `Command { std: "docker" "inspect" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", 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" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/ecat_middleware-3d3f18274918d4f5)
[INFO] [stdout] 
[INFO] [stdout] running 37 tests
[INFO] [stdout] test logging::tests::layer_constructs ... ok
[INFO] [stdout] test ratelimit::tests::default_key_fn_ignores_forwarded_for_by_default ... ok
[INFO] [stdout] test logging::tests::calls_inner_service ... ok
[INFO] [stdout] test ratelimit::tests::default_key_fn_prefers_connect_info_over_spoofed_header ... ok
[INFO] [stdout] test ratelimit::tests::rate_limiter_allows_within_limit ... ok
[INFO] [stdout] test ratelimit::tests::default_key_fn_global_without_ip_headers ... ok
[INFO] [stdout] test ratelimit::tests::rate_limiter_separate_keys ... ok
[INFO] [stdout] test ratelimit::tests::layer_with_custom_key ... ok
[INFO] [stdout] test ratelimit::tests::memory_store_sweep_bounds_map_size ... ok
[INFO] [stdout] test ratelimit::tests::default_key_fn_uses_real_ip_fallback_when_trusted ... ok
[INFO] [stdout] test recovery::tests::layer_wraps_service ... ok
[INFO] [stdout] test ratelimit::tests::rate_limiter_blocks_over_limit ... ok
[INFO] [stdout] test ratelimit::tests::layer_constructs ... ok
[INFO] [stdout] test ratelimit::tests::rate_limit_error_maps_to_429 ... ok
[INFO] [stdout] test recovery::tests::layer_constructs ... ok
[INFO] [stdout] test retry::tests::exponential_backoff_caps_at_max_delay ... ok
[INFO] [stdout] test retry::tests::non_retryable_rule_does_not_retry ... ok
[INFO] [stdout] test logging::tests::layer_wraps_service ... ok
[INFO] [stdout] test ratelimit::tests::rate_limiter_zero_max_blocks_everything ... ok
[INFO] [stdout] test timeout::tests::layer_constructs_with_duration ... ok
[INFO] [stdout] test ratelimit::tests::trust_proxy_headers_flag_flows_into_layer_key_fn ... ok
[INFO] [stdout] test timeout::tests::calls_inner_service_within_timeout ... ok
[INFO] [stdout] test recovery::tests::calls_inner_service ... ok
[INFO] [stdout] test recovery::tests::inner_panic_becomes_error ... ok
[INFO] [stdout] test validate::tests::does_not_call_inner_on_failure ... ok
[INFO] [stdout] test validate::tests::from_fn_closure_entry_works ... ok
[INFO] [stdout] test validate::tests::mounts_on_axum_router ... ok
[INFO] [stdout] test validate::tests::passes_through_valid_requests ... ok
[INFO] [stdout] test validate::tests::rejects_with_custom_status ... ok
[INFO] [stdout] test validate::tests::rejects_with_default_400_and_json_body ... ok
[INFO] [stdout] test retry::tests::retries_until_max_attempts ... ok
[INFO] [stdout] test retry::tests::succeeds_on_retry ... ok
[INFO] [stdout] test timeout::tests::times_out_slow_service ... ok
[INFO] [stdout] test timeout::tests::layer_clones ... ok
[INFO] [stdout] test retry::tests::retry_waits_for_backoff_delay ... ok
[INFO] [stdout] test ratelimit::tests::default_key_fn_uses_forwarded_for_when_trusted ... ok
[INFO] [stderr]    Doc-tests ecat_middleware
[INFO] [stdout] test ratelimit::tests::memory_store_window_expiry_resets_bucket ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.11s
[INFO] [stdout] 
[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" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c", kill_on_drop: false }`
[INFO] [stdout] 7ac8f5234553687f0222841bad96c356b991f2cd9988244fc18a337c2c0ab22c
