[INFO] fetching crate timerwheel 0.1.0...
[INFO] testing timerwheel-0.1.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate timerwheel 0.1.0 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate timerwheel 0.1.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate timerwheel 0.1.0
[INFO] tweaked toml for crates.io crate timerwheel 0.1.0 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate timerwheel 0.1.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 timerwheel 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" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1
[INFO] running `Command { std: "docker" "start" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", 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" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1" "/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" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", 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" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling timerwheel v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.63s
[INFO] running `Command { std: "docker" "inspect" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", 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" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling timerwheel v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.37s
[INFO] running `Command { std: "docker" "inspect" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", 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" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.59s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/timerwheel-87d851b014b548ff)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test timing_wheel::state::tests::dispatch_can_expire_or_reschedule ... ok
[INFO] [stdout] test timing_wheel::tests::very_far_deadline_cascades_across_multiple_levels ... ok
[INFO] [stdout] test timing_wheel::tests::immediate_deadline_is_due_without_bucket ... ok
[INFO] [stdout] test timing_wheel::tests::in_range_deadline_expires_from_bucket ... ok
[INFO] [stdout] test timing_wheel::state::tests::state_moves_from_scheduled_to_cancelled_once ... ok
[INFO] [stdout] test timing_wheel::bucket::tests::bucket_drains_entries_and_clears_expiration ... ok
[INFO] [stdout] test timing_wheel::tests::stale_delay_queue_offer_is_skipped ... ok
[INFO] [stdout] test timing_wheel::state::tests::dispatch_can_reject_once ... ok
[INFO] [stdout] test timing_wheel::tests::overflow_deadline_cascades_into_lower_level ... ok
[INFO] [stdout] test timing_wheel::delay_queue::tests::delay_queue_orders_by_expiration ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builder_api.rs (/opt/rustwide/target/debug/deps/builder_api-c5206ccebd3a0141)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test pool_builder_rejects_zero_workers ... ok
[INFO] [stdout] test pool_builder_rejects_zero_queue_capacity ... ok
[INFO] [stdout] test timer_builder_rejects_zero_tick ... ok
[INFO] [stdout] test timer_builder_rejects_zero_max_pending ... ok
[INFO] [stdout] test timer_builder_rejects_zero_command_capacity ... ok
[INFO] [stdout] test timer_builder_rejects_zero_bucket_count ... ok
[INFO] [stdout] test timer_accepts_erased_executor ... ok
[INFO] [stdout] test timer_uses_supplied_executor ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/executor_api.rs (/opt/rustwide/target/debug/deps/executor_api-c03df159f9905c5c)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test pool_executes_trait_object_task ... ok
[INFO] [stdout] test executor_can_be_used_as_trait_object ... ok
[INFO] [stdout] test pool_try_execute_returns_task_when_full ... ok
[INFO] [stdout] test pool_executes_tasks ... ok
[INFO] [stdout] test pool_try_execute_rejects_when_queue_is_full ... ok
[INFO] [stdout] test rejected_task_returns_original_task ... ok
[INFO] [stdout] test pool_reports_metrics_to_metric_sink ... ok
[INFO] [stdout] test pool_shutdown_is_idempotent_and_closes_submission ... ok
[INFO] [stdout] test pool_recovers_from_task_panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/prelude_api.rs (/opt/rustwide/target/debug/deps/prelude_api-85dc4e294fc78746)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test prelude_imports_core_timer_executor_and_metric_sink_aliases ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/timer_api.rs (/opt/rustwide/target/debug/deps/timer_api-abc1485122166bd3)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test scheduler_keeps_draining_commands_while_executor_rejects ... ok
[INFO] [stdout] test scheduler_retries_rejected_dispatch_without_losing_task ... ok
[INFO] [stdout] test cancelled_timeout_does_not_run_task ... ok
[INFO] [stdout] test timer_rejects_when_max_pending_is_reached ... ok
[INFO] [stdout] test timer_releases_pending_capacity_after_dispatch_not_task_completion ... ok
[INFO] [stdout] test timer_reports_metrics_to_metric_sink ... ok
[INFO] [stdout] test timer_executes_delayed_task ... ok
[INFO] [stdout] test timer_shutdown_releases_pending_timeouts ... ok
[INFO] [stdout] test timer_rejects_delay_that_is_too_large ... ok
[INFO] [stdout] test timer_shutdown_is_idempotent_and_closes_schedule_path ... ok
[INFO] [stdout] test long_running_worker_task_does_not_block_schedule_and_cancel ... ok
[INFO] [stdout] test timer_releases_capacity_after_too_large_delay_rejection ... ok
[INFO] [stdout] test timer_updates_metrics_for_expire_and_cancel ... ok
[INFO] [stdout] test scheduler_runs_retried_task_once_after_acceptance ... ok
[INFO] [stdout] test timer_retry_dispatch_keeps_task_until_executor_accepts_it ... ok
[INFO] [stdout] test timer_releases_pending_timeout_on_task_panic ... ok
[INFO] [stdout] test timer_cascades_far_deadline ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tokio_api.rs (/opt/rustwide/target/debug/deps/tokio_api-1c9909dd9dfb4ffa)
[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 timerwheel
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 28) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.40s; merged doctests compilation took 0.37s
[INFO] running `Command { std: "docker" "inspect" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1", kill_on_drop: false }`
[INFO] [stdout] b0f6d1c24fbbebbccfb51d0a8617b6c792e6e467e3954ad45bc31c950f7a91a1
