[INFO] fetching crate darkbio-clock 0.4.0...
[INFO] testing darkbio-clock-0.4.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate darkbio-clock 0.4.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate darkbio-clock 0.4.0
[INFO] finished tweaking crates.io crate darkbio-clock 0.4.0
[INFO] tweaked toml for crates.io crate darkbio-clock 0.4.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate darkbio-clock 0.4.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 darkbio-clock 0.4.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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362
[INFO] running `Command { std: "docker" "start" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", 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" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362" "/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" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", 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" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling darkbio-clock v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.38s
[INFO] running `Command { std: "docker" "inspect" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", 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" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zerocopy v0.8.58
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling darkbio-clock v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 22.81s
[INFO] running `Command { std: "docker" "inspect" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", 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" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/darkbio-clock/4bbe51bef6fa51f0/out/darkbio_clock-4bbe51bef6fa51f0)
[INFO] [stdout] 
[INFO] [stdout] running 55 tests
[INFO] [stdout] test sync::tests::test_mutex_try_lock_reports_contention ... ok
[INFO] [stdout] test sync::tests::test_next_deadline_clamps_reached_registration_to_current_time ... ok
[INFO] [stdout] test sync::tests::test_advance_ends_every_reached_wait_on_a_condvar ... ok
[INFO] [stdout] test sync::tests::test_later_wait_cannot_claim_earlier_notification ... ok
[INFO] [stdout] test sync::tests::test_notification_between_predicate_and_registration_is_observed ... ok
[INFO] [stdout] test sync::tests::test_notified_wait_does_not_time_out_on_late_relock ... ok
[INFO] [stdout] test sync::tests::test_deadline_worker_recomputes_earliest_deadline ... ok
[INFO] [stdout] test sync::tests::test_consumed_notification_leaves_sibling_to_time_out ... ok
[INFO] [stdout] test sync::tests::test_advance_deduplicates_nonadjacent_signal_deadlines ... ok
[INFO] [stdout] test sync::tests::test_later_wait_on_a_reached_condvar_stays_listed ... ok
[INFO] [stdout] test sync::tests::test_mutex_accessors_preserve_poisoned_values ... ok
[INFO] [stdout] test sync::tests::test_mutex_poisoning_preserves_guard_and_recovers ... ok
[INFO] [stdout] test sync::tests::test_advances_never_wake_an_untimed_wait ... ok
[INFO] [stdout] test sync::tests::test_advances_wake_only_the_waits_they_reach ... ok
[INFO] [stdout] test sync::tests::test_wait_claims_oldest_eligible_notification ... ok
[INFO] [stdout] test sync::tests::test_consumed_notification_keeps_unreached_sibling_listed ... ok
[INFO] [stdout] test sync::tests::test_wait_deadline_returns_for_reached_deadlines ... ok
[INFO] [stdout] test tests::clock::test_elapsed_saturates ... ok
[INFO] [stdout] test sync::tests::test_real_wait_deadline_expires ... ok
[INFO] [stdout] test sync::tests::test_wait_deadline_returns_early_on_notification ... ok
[INFO] [stdout] test tests::clock::test_clock_debug_uses_one_snapshot ... ok
[INFO] [stdout] test tests::clock::test_clock_identity_and_shared_time ... ok
[INFO] [stdout] test sync::tests::test_mutex_and_guards_format_like_std_without_blocking ... ok
[INFO] [stdout] test sync::tests::test_wait_while_rechecks_predicate_after_notifications ... ok
[INFO] [stdout] test sync::tests::test_next_deadline_tracks_only_registered_timed_waits ... ok
[INFO] [stdout] test sync::tests::test_wait_deadline_observes_advance_before_registration ... ok
[INFO] [stdout] test tests::clock::test_failed_wall_advance_keeps_both_times ... ok
[INFO] [stdout] test tests::clock::test_registration_retains_signal_until_removed ... ok
[INFO] [stdout] test tests::clock::test_notify_wakes_all_registered_waiters ... ok
[INFO] [stdout] test tests::clock::test_failed_monotonic_advance_keeps_both_times ... ok
[INFO] [stdout] test tests::clock::test_next_deadline_drives_sleeps_in_order ... ok
[INFO] [stdout] test tests::clock::test_debug_writer_can_read_clock ... ok
[INFO] [stdout] test tests::clock::test_poisoned_clock_remains_usable ... ok
[INFO] [stdout] test tests::clock::test_sleep_returns_for_reached_deadlines ... ok
[INFO] [stdout] test tests::clock::test_system_time_follows_advances_and_jumps_alone ... ok
[INFO] [stdout] test tests::clock::test_spurious_wakeup_keeps_wait_registered ... ok
[INFO] [stdout] test tests::clock::test_advance_wakes_all_registered_sleepers ... ok
[INFO] [stdout] test tests::clock::test_real_sleep_until_reaches_deadline ... ok
[INFO] [stdout] test tests::clock::test_real_sleep_reaches_duration ... ok
[INFO] [stdout] test tests::clock::test_sleep_observes_advance_before_registration ... ok
[INFO] [stdout] test tests::clock::test_removing_registration_keeps_shared_deadline_listed ... ok
[INFO] [stdout] test tests::clock::test_wait_observes_advance_before_registration ... ok
[INFO] [stdout] test sync::tests::test_broadcast_followed_by_single_notification_strands_nothing ... ok
[INFO] [stdout] test tests::clock::test_wait_observes_notification_before_registration ... ok
[INFO] [stdout] test tests::clock::test_wait_returns_for_reached_deadline ... ok
[INFO] [stdout] test sync::tests::test_wait_relocks_poisoned_mutex ... ok
[INFO] [stdout] test sync::tests::test_single_notification_releases_exactly_one_reached_wait ... ok
[INFO] [stdout] test tests::settlement::test_condvar_deadline_change_holds_parked_barrier ... ok
[INFO] [stdout] test tests::settlement::test_reached_registration_cannot_satisfy_parked_barrier ... ok
[INFO] [stdout] test tests::clock::test_wall_time_accumulates_fractional_advances ... ok
[INFO] [stdout] test tests::settlement::test_notification_keeps_registration_but_holds_parked_barrier ... ok
[INFO] [stdout] test tests::clock::test_wall_jumps_and_zero_advances_do_not_wake ... ok
[INFO] [stdout] test sync::tests::test_notifications_end_waits ... ok
[INFO] [stdout] test tests::settlement::test_parked_barrier_waits_for_notifications_on_any_condvar ... ok
[INFO] [stderr]    Doc-tests darkbio_clock
[INFO] [stdout] test tests::settlement::test_single_notification_leaves_unselected_wait_parked ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 55 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/sync.rs - sync (line 14) ... ok
[INFO] [stdout] test src/../README.md - (line 26) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.41s; merged doctests compilation took 0.40s
[INFO] running `Command { std: "docker" "inspect" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362", kill_on_drop: false }`
[INFO] [stdout] 5110c109bcc4614a023ea63ca49738935a348375a84cbc7d2d5eefc2d4534362
