[INFO] fetching crate limit3r 0.1.0...
[INFO] testing limit3r-0.1.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate limit3r 0.1.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate limit3r 0.1.0
[INFO] finished tweaking crates.io crate limit3r 0.1.0
[INFO] tweaked toml for crates.io crate limit3r 0.1.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate limit3r 0.1.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate limit3r 0.1.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" "+562dee4820c458d823175268e41601d4c060588a" "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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 12429b0dae6c6ec5cd1167373961b52c6c294d210710ab2dfab1c31fb89d82b3
[INFO] running `Command { std: "docker" "start" "-a" "12429b0dae6c6ec5cd1167373961b52c6c294d210710ab2dfab1c31fb89d82b3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "12429b0dae6c6ec5cd1167373961b52c6c294d210710ab2dfab1c31fb89d82b3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "12429b0dae6c6ec5cd1167373961b52c6c294d210710ab2dfab1c31fb89d82b3", kill_on_drop: false }`
[INFO] [stdout] 12429b0dae6c6ec5cd1167373961b52c6c294d210710ab2dfab1c31fb89d82b3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 44dd38c308345fe0e6a969e914e15c7d002b8c7a0d4e7e5e9ec5920d7c700c45
[INFO] running `Command { std: "docker" "start" "-a" "44dd38c308345fe0e6a969e914e15c7d002b8c7a0d4e7e5e9ec5920d7c700c45", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling limit3r v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.91s
[INFO] running `Command { std: "docker" "inspect" "44dd38c308345fe0e6a969e914e15c7d002b8c7a0d4e7e5e9ec5920d7c700c45", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "44dd38c308345fe0e6a969e914e15c7d002b8c7a0d4e7e5e9ec5920d7c700c45", kill_on_drop: false }`
[INFO] [stdout] 44dd38c308345fe0e6a969e914e15c7d002b8c7a0d4e7e5e9ec5920d7c700c45
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fdfb9a243f250407f0d0c51ba98db25f7333de3f371bed3ca0b64a766eee7d49
[INFO] running `Command { std: "docker" "start" "-a" "fdfb9a243f250407f0d0c51ba98db25f7333de3f371bed3ca0b64a766eee7d49", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling limit3r v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.93s
[INFO] running `Command { std: "docker" "inspect" "fdfb9a243f250407f0d0c51ba98db25f7333de3f371bed3ca0b64a766eee7d49", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fdfb9a243f250407f0d0c51ba98db25f7333de3f371bed3ca0b64a766eee7d49", kill_on_drop: false }`
[INFO] [stdout] fdfb9a243f250407f0d0c51ba98db25f7333de3f371bed3ca0b64a766eee7d49
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 39c529e866a425dc7bb46f77823b9c72adf61cc1032ba6f22e9f0288c99072ae
[INFO] running `Command { std: "docker" "start" "-a" "39c529e866a425dc7bb46f77823b9c72adf61cc1032ba6f22e9f0288c99072ae", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/limit3r-0a62cec1351fa3bf)
[INFO] [stdout] 
[INFO] [stdout] running 99 tests
[INFO] [stdout] test bulkhead::tests::eviction_triggers_when_exceeding_max_keys_bulkhead ... ok
[INFO] [stdout] test bulkhead::tests::no_eviction_below_max_keys_bulkhead ... ok
[INFO] [stdout] test bulkhead::tests::eviction_keeps_keys_with_outstanding_permits ... ok
[INFO] [stdout] test bulkhead::tests::releases_permit_correctly ... ok
[INFO] [stdout] test circuit_breaker::tests::eviction_keeps_open_circuits ... ok
[INFO] [stdout] test circuit_breaker::tests::does_not_trip_just_below_threshold ... ok
[INFO] [stdout] test circuit_breaker::tests::eviction_preserves_failure_history ... ok
[INFO] [stdout] test circuit_breaker::tests::eviction_excludes_current_key_in_second_pass ... ok
[INFO] [stdout] test circuit_breaker::tests::eviction_triggers_when_exceeding_max_keys_circuit_breaker ... ok
[INFO] [stdout] test circuit_breaker::tests::first_pass_retains_non_closed_circuits ... ok
[INFO] [stdout] test circuit_breaker::tests::opens_after_failure_threshold_exceeded ... ok
[INFO] [stdout] test circuit_breaker::tests::regression_circuit_breaker_does_not_trip_on_partial_window ... ok
[INFO] [stdout] test circuit_breaker::tests::no_eviction_at_exactly_max_keys_circuit_breaker ... ok
[INFO] [stdout] test circuit_breaker::tests::second_pass_evicts_fewest_results_first ... ok
[INFO] [stdout] test circuit_breaker::tests::eviction_removes_closed_empty_first ... ok
[INFO] [stdout] test circuit_breaker::tests::starts_closed_and_permits_requests ... ok
[INFO] [stdout] test circuit_breaker::tests::second_pass_only_evicts_closed ... ok
[INFO] [stdout] test circuit_breaker::tests::single_failure_does_not_trip_with_large_window ... ok
[INFO] [stdout] test config::tests::bulkhead_config_accepts_valid ... ok
[INFO] [stdout] test circuit_breaker::tests::trim_to_window_actually_trims ... ok
[INFO] [stdout] test config::tests::bulkhead_config_rejects_zero_max_concurrent ... ok
[INFO] [stdout] test config::tests::bulkhead_config_serde_round_trip ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_rejects_negative_threshold ... ok
[INFO] [stdout] test bulkhead::tests::no_eviction_at_exactly_max_keys_bulkhead ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_rejects_threshold_over_100 ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_rejects_zero_wait_duration ... ok
[INFO] [stdout] test circuit_breaker::tests::partial_window_below_min_calls_stays_closed ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_serde_round_trip ... ok
[INFO] [stdout] test config::tests::rate_limit_config_accepts_valid ... ok
[INFO] [stdout] test config::tests::rate_limit_config_rejects_zero_limit_for_period ... ok
[INFO] [stdout] test circuit_breaker::tests::rejects_requests_when_open ... ok
[INFO] [stdout] test config::tests::rate_limit_config_rejects_zero_refresh_period ... ok
[INFO] [stdout] test config::tests::rate_limit_config_rejects_zero_timeout ... ok
[INFO] [stdout] test config::tests::rate_limit_config_serde_round_trip ... ok
[INFO] [stdout] test config::tests::regression_config_validation_rejects_invalid_values ... ok
[INFO] [stdout] test config::tests::retry_config_accepts_valid ... ok
[INFO] [stdout] test config::tests::retry_config_rejects_infinite_multiplier ... ok
[INFO] [stdout] test config::tests::retry_config_rejects_nan_multiplier ... ok
[INFO] [stdout] test config::tests::retry_config_rejects_negative_multiplier ... ok
[INFO] [stdout] test config::tests::retry_config_rejects_zero_attempts ... ok
[INFO] [stdout] test config::tests::retry_config_rejects_zero_multiplier ... ok
[INFO] [stdout] test duration_serde::non_option_guard_tests::mutant_kill_non_option_accepts_valid_positive ... ok
[INFO] [stdout] test duration_serde::non_option_guard_tests::mutant_kill_non_option_or_vs_and ... ok
[INFO] [stdout] test duration_serde::non_option_guard_tests::mutant_kill_v2_non_option_each_guard_condition_independent ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_accepts_zero ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_deserialize_missing_field ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_deserialize_some_value ... ok
[INFO] [stdout] test bulkhead::tests::acquires_permit_when_under_max_concurrent ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_guard_all_conditions_independent ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_guard_replaced_with_false ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_guard_replaced_with_true ... ok
[INFO] [stdout] test config::tests::retry_config_serde_round_trip ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_rejects_negative ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_rejects_negative_large ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_v2_option_each_guard_condition_independent ... ok
[INFO] [stdout] test duration_serde::tests::regression_duration_serde_rejects_negative_without_panic ... ok
[INFO] [stdout] test duration_serde::tests::rejects_infinity_duration ... ok
[INFO] [stdout] test duration_serde::tests::rejects_nan_duration ... ok
[INFO] [stdout] test duration_serde::tests::rejects_negative_duration ... ok
[INFO] [stdout] test duration_serde::tests::round_trip_positive_duration ... ok
[INFO] [stdout] test duration_serde::tests::round_trip_zero_duration ... ok
[INFO] [stdout] test rate_limiter::tests::acquire_permit_fails_when_limit_exhausted_and_timeout_expires ... ok
[INFO] [stdout] test rate_limiter::tests::acquire_permit_succeeds_when_under_limit ... ok
[INFO] [stdout] test rate_limiter::tests::deadline_exceeded_returns_error ... ok
[INFO] [stdout] test rate_limiter::tests::deadline_tight_timeout_returns_error ... ok
[INFO] [stdout] test rate_limiter::tests::debug_fmt_outputs_type_name ... ok
[INFO] [stdout] test rate_limiter::tests::eviction_does_not_evict_current_key ... ok
[INFO] [stdout] test rate_limiter::tests::eviction_preserves_current_key_permit_state ... ok
[INFO] [stdout] test rate_limiter::tests::eviction_removes_oldest_when_no_expired ... ok
[INFO] [stdout] test duration_serde::option_tests::mutant_kill_option_deserialize_null ... ok
[INFO] [stdout] test rate_limiter::tests::debug_fmt_key_state_outputs_field_names ... ok
[INFO] [stdout] test rate_limiter::tests::no_eviction_at_exactly_max_keys ... ok
[INFO] [stdout] test rate_limiter::tests::eviction_triggers_when_exceeding_max_keys ... ok
[INFO] [stdout] test retry::tests::compute_delay_with_exponential_backoff ... ok
[INFO] [stdout] test circuit_breaker::tests::regression_circuit_breaker_trips_at_exact_threshold_with_window_2 ... ok
[INFO] [stdout] test circuit_breaker::tests::trips_at_exact_threshold_rate ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_accepts_boundary_thresholds ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_accepts_valid ... ok
[INFO] [stdout] test retry::tests::mutant_kill_compute_delay_nan_returns_zero ... ok
[INFO] [stdout] test retry::tests::mutant_kill_compute_delay_exactly_at_max_returns_max ... ok
[INFO] [stdout] test retry::tests::mutant_kill_compute_delay_negative_factor_returns_zero ... ok
[INFO] [stdout] test retry::tests::mutant_kill_compute_delay_just_below_max_not_clamped ... ok
[INFO] [stdout] test rate_limiter::tests::multiple_keys_are_independent ... ok
[INFO] [stdout] test retry::tests::mutant_kill_v2_delay_exactly_equal_max_returns_max ... ok
[INFO] [stdout] test retry::tests::mutant_kill_v2_delay_exactly_zero_not_rejected ... ok
[INFO] [stdout] test config::tests::circuit_breaker_config_rejects_zero_window ... ok
[INFO] [stdout] test retry::tests::succeeds_on_first_attempt_no_retry ... ok
[INFO] [stdout] test retry::tests::mutant_kill_compute_delay_negative_not_equal_zero ... ok
[INFO] [stdout] test rate_limiter::tests::eviction_removes_expired_windows_first ... ok
[INFO] [stdout] test retry::tests::exhausts_all_attempts_and_returns_retry_exhausted ... ok
[INFO] [stdout] test retry::tests::succeeds_after_retry ... ok
[INFO] [stdout] test bulkhead::tests::config_match_returns_cached_semaphore ... ok
[INFO] [stdout] test bulkhead::tests::config_change_replaces_semaphore ... ok
[INFO] [stdout] test bulkhead::tests::times_out_when_all_permits_taken ... ok
[INFO] [stdout] test circuit_breaker::tests::closes_again_after_successful_probe ... ok
[INFO] [stdout] test circuit_breaker::tests::reopens_after_failed_probe ... ok
[INFO] [stdout] test circuit_breaker::tests::transitions_to_half_open_after_wait_duration ... ok
[INFO] [stdout] test bulkhead::tests::eviction_does_not_evict_current_key_bulkhead ... ok
[INFO] [stdout] test rate_limiter::tests::window_resets_after_refresh_period ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 99 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests limit3r
[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" "39c529e866a425dc7bb46f77823b9c72adf61cc1032ba6f22e9f0288c99072ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "39c529e866a425dc7bb46f77823b9c72adf61cc1032ba6f22e9f0288c99072ae", kill_on_drop: false }`
[INFO] [stdout] 39c529e866a425dc7bb46f77823b9c72adf61cc1032ba6f22e9f0288c99072ae