[INFO] fetching crate rainier-queue 2.0.0...
[INFO] testing rainier-queue-2.0.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rainier-queue 2.0.0 into /workspace/builds/worker-1-tc2/source
[INFO] started tweaking crates.io crate rainier-queue 2.0.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rainier-queue 2.0.0
[INFO] tweaked toml for crates.io crate rainier-queue 2.0.0 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rainier-queue 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-queue 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-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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] 04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07
[INFO] running `Command { std: "docker" "start" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", 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" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07" "/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" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", 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" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling http v1.4.2
[INFO] [stderr]    Compiling heck v0.4.1
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling futures-core v0.3.33
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling mio v1.2.2
[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_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling async-trait v0.1.91
[INFO] [stderr]    Compiling inherent v1.0.14
[INFO] [stderr]    Compiling darling_core v0.20.11
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.7.1
[INFO] [stderr]    Compiling rainier-orm-macros v2.0.0
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling sea-query-derive v0.4.3
[INFO] [stderr]    Compiling rainier-support v2.0.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling rainier-drivers v2.0.0
[INFO] [stderr]    Compiling rainier-events v2.0.0
[INFO] [stderr]    Compiling rainier-http v2.0.0
[INFO] [stderr]    Compiling sea-query v0.32.7
[INFO] [stderr]    Compiling rainier-orm v2.0.0
[INFO] [stderr]    Compiling rainier-container v2.0.0
[INFO] [stderr]    Compiling rainier-middleware v2.0.0
[INFO] [stderr]    Compiling rainier-cache v2.0.0
[INFO] [stderr]    Compiling rainier-database v2.0.0
[INFO] [stderr]    Compiling rainier-queue v2.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 24.36s
[INFO] running `Command { std: "docker" "inspect" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", 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" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07" "/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 rainier-queue v2.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4.20s
[INFO] running `Command { std: "docker" "inspect" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", 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" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07" "/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.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rainier-queue/d717c1ced5ed35ab/out/rainier_queue-d717c1ced5ed35ab)
[INFO] [stdout] 
[INFO] [stdout] running 71 tests
[INFO] [stdout] test database::tests::losing_the_claim_race_yields_no_job ... ok
[INFO] [stdout] test database::tests::a_queued_job_round_trips_through_a_row ... ok
[INFO] [stdout] test database::tests::pushing_writes_a_row ... ok
[INFO] [stdout] test database::tests::a_reserved_job_reports_the_attempt_the_row_now_holds ... ok
[INFO] [stdout] test database::tests::reclaiming_releases_jobs_held_past_their_deadline ... ok
[INFO] [stdout] test database::tests::releasing_clears_the_reservation_and_pushes_availability_out ... ok
[INFO] [stdout] test job::tests::a_job_can_declare_its_own_queue_and_tries ... ok
[INFO] [stdout] test database::tests::failing_records_the_row_before_removing_the_job ... ok
[INFO] [stdout] test database::tests::the_row_entities_describe_their_tables ... ok
[INFO] [stdout] test job::tests::backoff_grows_and_then_levels_off ... ok
[INFO] [stdout] test job::tests::the_context_reports_the_attempt_window ... ok
[INFO] [stdout] test database::tests::reserving_selects_only_free_available_jobs ... ok
[INFO] [stdout] test job::tests::the_registry_lists_what_it_knows ... ok
[INFO] [stdout] test database::tests::a_claim_is_conditional_on_the_job_still_being_free ... ok
[INFO] [stdout] test manager::tests::a_fake_records_instead_of_queueing ... ok
[INFO] [stdout] test manager::tests::dispatch_now_bypasses_the_queue ... ok
[INFO] [stdout] test manager::tests::dispatch_on_and_after_are_shorthands ... ok
[INFO] [stdout] test manager::tests::dispatch_options_apply ... ok
[INFO] [stdout] test manager::tests::dispatching_enqueues_the_job ... ok
[INFO] [stdout] test job::tests::queued_ids_are_unique ... ok
[INFO] [stdout] test job::tests::the_registry_runs_a_job_by_name ... ok
[INFO] [stdout] test job::tests::the_failed_hook_only_fires_on_the_last_attempt ... ok
[INFO] [stdout] test manager::tests::the_sync_driver_runs_the_job_immediately ... ok
[INFO] [stdout] test manager::tests::the_sync_driver_surfaces_a_failure_to_the_dispatcher ... ok
[INFO] [stdout] test manager::unique_tests::a_configured_dispatch_is_deduplicated_too ... ok
[INFO] [stdout] test manager::unique_tests::a_pending_dispatch_that_is_dropped_leaves_no_lock ... ok
[INFO] [stdout] test manager::unique_tests::a_second_identical_dispatch_is_dropped ... ok
[INFO] [stdout] test manager::unique_tests::releasing_the_lock_lets_the_next_one_in ... ok
[INFO] [stdout] test manager::unique_tests::the_queued_job_carries_the_key_so_a_worker_can_release_it ... ok
[INFO] [stdout] test database::tests::size_counts_only_unreserved_jobs ... ok
[INFO] [stdout] test manager::unique_tests::without_a_lock_manager_a_unique_job_is_dispatched_anyway ... ok
[INFO] [stdout] test manager::unique_tests::two_job_types_sharing_an_id_do_not_collide ... ok
[INFO] [stdout] test driver::tests::sync_is_the_only_driver_that_runs_inline ... ok
[INFO] [stdout] test manager::unique_tests::an_ordinary_job_is_never_deduplicated ... ok
[INFO] [stdout] test driver::tests::only_the_backed_drivers_survive_a_restart ... ok
[INFO] [stdout] test manager::unique_tests::different_ids_are_different_jobs ... ok
[INFO] [stdout] test job::tests::an_unregistered_job_names_itself_and_what_is_known ... ok
[INFO] [stdout] test job::tests::a_payload_that_no_longer_fits_its_type_is_reported_clearly ... ok
[INFO] [stdout] test database::tests::the_driver_ships_its_own_migrations ... ok
[INFO] [stdout] test database::tests::acknowledging_deletes_the_row ... ok
[INFO] [stdout] test queue::tests::a_delayed_job_does_not_block_the_ones_behind_it ... ok
[INFO] [stdout] test job::tests::a_delay_pushes_availability_into_the_future ... ok
[INFO] [stdout] test job::tests::a_job_serialises_with_its_name_and_defaults ... ok
[INFO] [stdout] test driver::tests::only_redis_can_lose_a_job_it_already_accepted ... ok
[INFO] [stdout] test queue::tests::a_pushed_job_can_be_reserved ... ok
[INFO] [stdout] test queue::tests::acknowledging_removes_it_for_good ... ok
[INFO] [stdout] test queue::tests::a_reserved_job_is_invisible_to_other_workers ... ok
[INFO] [stdout] test queue::tests::clearing_discards_everything_pending ... ok
[INFO] [stdout] test queue::tests::failing_moves_it_to_the_failed_store ... ok
[INFO] [stdout] test queue::tests::an_empty_queue_reserves_nothing ... ok
[INFO] [stdout] test queue::tests::queues_are_independent ... ok
[INFO] [stdout] test queue::tests::reserving_increments_the_attempt_count ... ok
[INFO] [stdout] test queue::tests::releasing_with_a_delay_holds_it_back ... ok
[INFO] [stdout] test queue::tests::releasing_puts_it_back_with_its_attempt_count_kept ... ok
[INFO] [stdout] test queue::tests::jobs_come_back_in_the_order_they_went_in ... ok
[INFO] [stdout] test worker::tests::a_run_drains_the_queue_and_reports_what_it_did ... ok
[INFO] [stdout] test worker::tests::a_stopping_worker_leaves_the_loop ... ok
[INFO] [stdout] test worker::tests::a_job_that_exhausts_its_attempts_fails ... ok
[INFO] [stdout] test worker::tests::an_unknown_job_fails_rather_than_stalling_the_worker ... ok
[INFO] [stdout] test worker::tests::a_successful_job_is_acknowledged ... ok
[INFO] [stdout] test worker::tests::lifecycle_events_are_fired ... ok
[INFO] [stdout] test worker::tests::the_backoff_curve_grows_and_levels_off ... ok
[INFO] [stdout] test worker::tests::a_worker_stops_after_its_job_limit ... ok
[INFO] [stdout] test worker::tests::an_empty_queue_is_idle ... ok
[INFO] [stdout] test worker::tests::queues_are_served_in_priority_order ... ok
[INFO] [stdout] test worker::tests::a_job_that_succeeds_on_retry_is_only_run_until_it_does ... ok
[INFO] [stdout] test worker::tests::a_failing_event_listener_does_not_stop_the_queue ... ok
[INFO] [stdout] test worker::tests::a_failing_job_is_released_for_another_attempt ... ok
[INFO] [stdout] test worker::tests::a_job_that_overruns_its_timeout_is_abandoned ... ok
[INFO] [stdout] test manager::tests::assertions_refuse_to_pass_vacuously - should panic ... ok
[INFO] [stdout] test manager::tests::a_missing_dispatch_reports_what_was_queued - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/kafka_queue.rs (/opt/rustwide/target/debug/build/rainier-queue/903195426a42d969/out/kafka_queue-903195426a42d969)
[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]      Running tests/redis_queue.rs (/opt/rustwide/target/debug/build/rainier-queue/04341b4d41344643/out/redis_queue-04341b4d41344643)
[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 rainier_queue
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test src/job.rs - job::Job::unique_id (line 157) ... ignored
[INFO] [stdout] test src/manager.rs - manager::QueueManager::pending (line 285) ... ignored
[INFO] [stdout] test src/job.rs - job::Job (line 96) ... ok
[INFO] [stdout] test src/driver.rs - driver::QueueDriver (line 7) ... ok
[INFO] [stdout] test src/lib.rs - (line 6) ... ok
[INFO] [stdout] test src/lib.rs - (line 56) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 1.13s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07", kill_on_drop: false }`
[INFO] [stdout] 04ddad150cadc947856efcdb14565fd41a9ce4b452d0f23758df9cf875243f07
