[INFO] fetching crate async-time-mock-core 0.1.4... [INFO] testing async-time-mock-core-0.1.4 against try#d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6 for pr-146237 [INFO] extracting crate async-time-mock-core 0.1.4 into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate async-time-mock-core 0.1.4 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate async-time-mock-core 0.1.4 [INFO] tweaked toml for crates.io crate async-time-mock-core 0.1.4 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate async-time-mock-core 0.1.4 on toolchain d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate async-time-mock-core 0.1.4 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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "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-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] ba61b9dbdfa787f680397d95db5c03b49bf546266cc09fdda7efb3d9de0970a4 [INFO] running `Command { std: "docker" "start" "-a" "ba61b9dbdfa787f680397d95db5c03b49bf546266cc09fdda7efb3d9de0970a4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "ba61b9dbdfa787f680397d95db5c03b49bf546266cc09fdda7efb3d9de0970a4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ba61b9dbdfa787f680397d95db5c03b49bf546266cc09fdda7efb3d9de0970a4", kill_on_drop: false }` [INFO] [stdout] ba61b9dbdfa787f680397d95db5c03b49bf546266cc09fdda7efb3d9de0970a4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 939a2873db96e32c7456d729333dffa4cc1b9e401072e450d5200f9890fb154f [INFO] running `Command { std: "docker" "start" "-a" "939a2873db96e32c7456d729333dffa4cc1b9e401072e450d5200f9890fb154f", kill_on_drop: false }` [INFO] [stderr] Compiling concurrent-queue v2.5.0 [INFO] [stderr] Compiling event-listener v5.3.1 [INFO] [stderr] Compiling event-listener-strategy v0.5.3 [INFO] [stderr] Compiling async-lock v3.4.0 [INFO] [stderr] Compiling async-time-mock-core v0.1.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.44s [INFO] running `Command { std: "docker" "inspect" "939a2873db96e32c7456d729333dffa4cc1b9e401072e450d5200f9890fb154f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "939a2873db96e32c7456d729333dffa4cc1b9e401072e450d5200f9890fb154f", kill_on_drop: false }` [INFO] [stdout] 939a2873db96e32c7456d729333dffa4cc1b9e401072e450d5200f9890fb154f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 13126810c6deabef9bf9fce05e2c84dd6ac1d48b7d45c96833c811cd3cc71a8f [INFO] running `Command { std: "docker" "start" "-a" "13126810c6deabef9bf9fce05e2c84dd6ac1d48b7d45c96833c811cd3cc71a8f", kill_on_drop: false }` [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling syn v2.0.95 [INFO] [stderr] Compiling futures-lite v2.5.0 [INFO] [stderr] Compiling tokio-macros v2.4.0 [INFO] [stderr] Compiling tokio v1.42.0 [INFO] [stderr] Compiling async-time-mock-core v0.1.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 6.29s [INFO] running `Command { std: "docker" "inspect" "13126810c6deabef9bf9fce05e2c84dd6ac1d48b7d45c96833c811cd3cc71a8f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "13126810c6deabef9bf9fce05e2c84dd6ac1d48b7d45c96833c811cd3cc71a8f", kill_on_drop: false }` [INFO] [stdout] 13126810c6deabef9bf9fce05e2c84dd6ac1d48b7d45c96833c811cd3cc71a8f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] e38e6bd7372c90586dab3e432a97945179e4c416ceb4fe4a2daf94d8b40d1104 [INFO] running `Command { std: "docker" "start" "-a" "e38e6bd7372c90586dab3e432a97945179e4c416ceb4fe4a2daf94d8b40d1104", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/async_time_mock_core-a9379f6019d03db3) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test time_handler_guard::test::should_notify_once_time_handler_guard_is_dropped ... ok [INFO] [stdout] test timer::test::time_handler_finished_should_be_triggered_by_time_handler_completion ... ok [INFO] [stdout] test await_all::test::should_run_all_futures ... ok [INFO] [stdout] test timer::test::timer_should_trigger_timer_listener ... ok [INFO] [stdout] test await_all::test::should_be_pending_if_one_future_is ... ok [INFO] [stdout] test instant::test::should_not_allow_fetching_duration_from_incorrect_timer_registry - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Running tests/instant.rs (/opt/rustwide/target/debug/deps/instant-a79563a6845f2a69) [INFO] [stdout] running 4 tests [INFO] [stdout] test should_not_allow_calculating_checked_duration_between_instants_from_different_timer_registries - should panic ... ok [INFO] [stdout] test should_not_allow_calculating_duration_between_instants_from_different_timer_registries - should panic ... ok [INFO] [stdout] test should_not_allow_calculating_saturated_duration_between_instants_from_different_timer_registries - should panic ... ok [INFO] [stdout] test should_not_allow_subtracting_two_instants_from_different_timer_registries - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stderr] Running tests/interval.rs (/opt/rustwide/target/debug/deps/interval-7191a14e18859583) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test should_schedule_periodic_timer ... ok [INFO] [stdout] test should_schedule_periodic_timer_at ... 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] [stderr] Running tests/timeout.rs (/opt/rustwide/target/debug/deps/timeout-2a5bf29184877075) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test timeout_should_finish_immediately ... ok [INFO] [stdout] test timeout_at_should_time_out ... ok [INFO] [stdout] test timeout_at_should_finish_immediately ... ok [INFO] [stdout] test timeout_should_time_out ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/timer_registry.rs (/opt/rustwide/target/debug/deps/timer_registry-1c2ad1a619aeda86) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stdout] test should_simulate_system_time ... ok [INFO] [stdout] test should_sleep_until ... ok [INFO] [stdout] test should_work_with_multiple_sleeps_of_same_length ... ok [INFO] [stdout] test sleep_should_finish_if_time_is_advanced_by_exactly_sleep_amount ... ok [INFO] [stdout] test should_work_with_multiple_sleeps_of_different_length_in_steps ... ok [INFO] [stdout] test sleep_should_not_finish_if_the_time_is_advanced_by_less_than_sleep_amount ... ok [INFO] [stdout] test sleep_until_should_resolve_after_zero_time_if_in_the_past ... ok [INFO] [stdout] test should_only_advance_time_once_the_first_timer_was_scheduled ... ok [INFO] [stdout] test should_work_with_multiple_sleeps_of_different_length_all_at_once ... ok [INFO] [stdout] test sleep_should_panic_with_zero_duration - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stderr] Running tests/use_cases.rs (/opt/rustwide/target/debug/deps/use_cases-c8b20b650133407c) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test should_not_deadlock_with_interval_and_timeout_of_same_length ... ok [INFO] [stderr] Doc-tests async_time_mock_core [INFO] [stdout] test operation_with_timeout_triggered_by_interval ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [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" "e38e6bd7372c90586dab3e432a97945179e4c416ceb4fe4a2daf94d8b40d1104", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e38e6bd7372c90586dab3e432a97945179e4c416ceb4fe4a2daf94d8b40d1104", kill_on_drop: false }` [INFO] [stdout] e38e6bd7372c90586dab3e432a97945179e4c416ceb4fe4a2daf94d8b40d1104