[INFO] fetching crate asynchronix 0.1.0...
[INFO] testing asynchronix-0.1.0 against 1.69.0 for beta-1.70-1
[INFO] extracting crate asynchronix 0.1.0 into /workspace/builds/worker-2-tc1/source
[INFO] validating manifest of crates.io crate asynchronix 0.1.0 on toolchain 1.69.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.69.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate asynchronix 0.1.0
[INFO] finished tweaking crates.io crate asynchronix 0.1.0
[INFO] tweaked toml for crates.io crate asynchronix 0.1.0 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] crate crates.io crate asynchronix 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.69.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded proc-macro2 v1.0.50
[INFO] [stderr]   Downloaded futures-macro v0.3.25
[INFO] [stderr]   Downloaded generator v0.7.2
[INFO] [stderr]   Downloaded futures-executor v0.3.25
[INFO] [stderr]   Downloaded st3 v0.4.1
[INFO] [stderr]   Downloaded windows_x86_64_msvc v0.39.0
[INFO] [stderr]   Downloaded windows_x86_64_gnu v0.39.0
[INFO] [stderr]   Downloaded windows_aarch64_msvc v0.39.0
[INFO] [stderr]   Downloaded windows_i686_msvc v0.39.0
[INFO] [stderr]   Downloaded windows_i686_gnu v0.39.0
[INFO] [stderr]   Downloaded windows v0.39.0
[INFO] [stderr]   Downloaded diatomic-waker v0.1.0
[INFO] [stderr]   Downloaded recycle-box v0.2.0
[INFO] [stderr]   Downloaded multishot v0.3.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 167a2b6fabe2f1249bc0e79a56b9c278a49ed8bcc060be97fbfaafe5b5d4b0b2
[INFO] running `Command { std: "docker" "start" "-a" "167a2b6fabe2f1249bc0e79a56b9c278a49ed8bcc060be97fbfaafe5b5d4b0b2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "167a2b6fabe2f1249bc0e79a56b9c278a49ed8bcc060be97fbfaafe5b5d4b0b2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "167a2b6fabe2f1249bc0e79a56b9c278a49ed8bcc060be97fbfaafe5b5d4b0b2", kill_on_drop: false }`
[INFO] [stdout] 167a2b6fabe2f1249bc0e79a56b9c278a49ed8bcc060be97fbfaafe5b5d4b0b2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 355c71c57a7c87fad6f7a35e38e6eb90d7d4029b5fe93b353ef31c1fd8a1c77c
[INFO] running `Command { std: "docker" "start" "-a" "355c71c57a7c87fad6f7a35e38e6eb90d7d4029b5fe93b353ef31c1fd8a1c77c", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.139
[INFO] [stderr]    Compiling crossbeam-utils v0.8.14
[INFO] [stderr]    Compiling futures-task v0.3.25
[INFO] [stderr]    Compiling recycle-box v0.2.0
[INFO] [stderr]    Compiling diatomic-waker v0.1.0
[INFO] [stderr]    Compiling multishot v0.3.0
[INFO] [stderr]    Compiling st3 v0.4.1
[INFO] [stderr]    Compiling num_cpus v1.15.0
[INFO] [stderr]    Compiling asynchronix v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.85s
[INFO] running `Command { std: "docker" "inspect" "355c71c57a7c87fad6f7a35e38e6eb90d7d4029b5fe93b353ef31c1fd8a1c77c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "355c71c57a7c87fad6f7a35e38e6eb90d7d4029b5fe93b353ef31c1fd8a1c77c", kill_on_drop: false }`
[INFO] [stdout] 355c71c57a7c87fad6f7a35e38e6eb90d7d4029b5fe93b353ef31c1fd8a1c77c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7c2f5b2f3ba04f603d1e19a3e11135a1f7a3e6ea11ddbf13d05eaefeafaf906c
[INFO] running `Command { std: "docker" "start" "-a" "7c2f5b2f3ba04f603d1e19a3e11135a1f7a3e6ea11ddbf13d05eaefeafaf906c", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.50
[INFO] [stderr]    Compiling unicode-ident v1.0.6
[INFO] [stderr]    Compiling quote v1.0.23
[INFO] [stderr]    Compiling futures-core v0.3.25
[INFO] [stderr]    Compiling syn v1.0.107
[INFO] [stderr]    Compiling futures-util v0.3.25
[INFO] [stderr]    Compiling futures-channel v0.3.25
[INFO] [stderr]    Compiling futures-macro v0.3.25
[INFO] [stderr]    Compiling futures-executor v0.3.25
[INFO] [stderr]    Compiling asynchronix v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 9.70s
[INFO] running `Command { std: "docker" "inspect" "7c2f5b2f3ba04f603d1e19a3e11135a1f7a3e6ea11ddbf13d05eaefeafaf906c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7c2f5b2f3ba04f603d1e19a3e11135a1f7a3e6ea11ddbf13d05eaefeafaf906c", kill_on_drop: false }`
[INFO] [stdout] 7c2f5b2f3ba04f603d1e19a3e11135a1f7a3e6ea11ddbf13d05eaefeafaf906c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4f17fb76016e3f7b8c535516991c9c5cf0d2b22bb5a631c96cfbf9b4df5bbf7a
[INFO] running `Command { std: "docker" "start" "-a" "4f17fb76016e3f7b8c535516991c9c5cf0d2b22bb5a631c96cfbf9b4df5bbf7a", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/asynchronix-351771f9401dac51)
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test channel::queue::tests::queue_closed_by_consumer ... ok
[INFO] [stdout] test channel::queue::tests::queue_closed_by_sender ... ok
[INFO] [stdout] test executor::task::tests::general::task_cancel_completed ... ok
[INFO] [stdout] test executor::task::tests::general::task_cancel_scheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_cancel_mt ... ok
[INFO] [stdout] test executor::task::tests::general::task_cancel_unscheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_schedule_and_forget ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_runnable ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_promise_mt ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_runnable_mt ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_promise_unscheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_promise_scheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_schedule ... ok
[INFO] [stdout] test executor::task::tests::general::task_wake_and_forget ... ok
[INFO] [stdout] test executor::task::tests::general::task_wake ... ok
[INFO] [stdout] test executor::task::tests::general::task_multiple_wake ... ok
[INFO] [stdout] test executor::task::tests::general::task_multiple_wake_mt ... ok
[INFO] [stdout] test executor::tests::executor_deadlock_st ... ok
[INFO] [stdout] test executor::tests::executor_drop_cycle ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_smoke ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_set ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_cycle ... ok
[INFO] [stdout] test executor::task::tests::general::task_schedule_mt ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_with_carry ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_unset ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_since_extreme ... ok
[INFO] [stdout] test executor::tests::executor_deadlock ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_extreme ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_from_system_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_since_invalid ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_with_carry ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_equality ... ok
[INFO] [stdout] test model::ports::broadcaster::tests::broadcast_event_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_overflow - should panic ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_overflow - should panic ... ok
[INFO] [stdout] test executor::task::tests::general::task_wake_mt ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_extreme ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_since_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_with_carry ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_ordering ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_panic_resets ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_delete_invalid ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_invalid - should panic ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_delete_valid ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_equal_keys ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_interleaved ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_smoke ... ok
[INFO] [stdout] test channel::queue::tests::queue_mpsc_capacity_one ... ok
[INFO] [stdout] test util::slot::tests::slot_single_threaded_drop_reader ... ok
[INFO] [stdout] test util::slot::tests::slot_multi_threaded_write ... ok
[INFO] [stdout] test util::slot::tests::slot_single_threaded_drop_writer ... ok
[INFO] [stdout] test util::slot::tests::slot_multi_threaded_drop_writer ... ok
[INFO] [stdout] test channel::queue::tests::queue_spsc_capacity_two ... ok
[INFO] [stdout] test channel::queue::tests::queue_mpsc_capacity_three ... ok
[INFO] [stdout] test util::spsc_queue::tests::spsc_queue_basic ... ok
[INFO] [stdout] test channel::queue::tests::queue_spsc_capacity_one ... ok
[INFO] [stdout] test util::slot::tests::slot_single_threaded_write ... ok
[INFO] [stdout] test channel::queue::tests::queue_spsc_capacity_three ... ok
[INFO] [stdout] test channel::queue::tests::queue_mpsc_capacity_two ... ok
[INFO] [stdout] test util::rng::tests::rng_gen_bounded_chi2 ... ok
[INFO] [stdout] test model::ports::broadcaster::tests::broadcast_query_smoke ... ok
[INFO] [stdout] test util::bit::tests::find_bit_fuzz ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_fuzz ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 65 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 9.21s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests asynchronix
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test src/model.rs - model (line 101) ... ignored
[INFO] [stdout] test src/model.rs - model (line 121) ... ignored
[INFO] [stdout] test src/model/model_fn.rs - model::model_fn::InputFn (line 14) ... ignored
[INFO] [stdout] test src/model/model_fn.rs - model::model_fn::InputFn (line 25) ... ignored
[INFO] [stdout] test src/model/model_fn.rs - model::model_fn::ReplierFn (line 126) ... ignored
[INFO] [stdout] test src/model/model_fn.rs - model::model_fn::ReplierFn (line 135) ... ignored
[INFO] [stdout] test src/model.rs - model (line 16) ... ok
[INFO] [stdout] test src/model.rs - model::Model::init (line 201) ... ok
[INFO] [stdout] test src/lib.rs - (line 80) ... ok
[INFO] [stdout] test src/model.rs - model (line 27) ... ok
[INFO] [stdout] test src/time.rs - time (line 17) ... ok
[INFO] [stdout] test src/model.rs - model (line 143) ... ok
[INFO] [stdout] test src/time/scheduler.rs - time::scheduler::Scheduler (line 34) ... ignored
[INFO] [stdout] test src/lib.rs - (line 105) ... ok
[INFO] [stdout] test src/model.rs - model (line 77) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::checked_add (line 247) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime (line 40) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::as_secs (line 168) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::checked_duration_since (line 360) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::as_unix_secs (line 206) ... ok
[INFO] [stdout] test src/time/scheduler.rs - time::scheduler::Scheduler (line 53) ... ok
[INFO] [stdout] test src/time/scheduler.rs - time::scheduler::Scheduler<M>::schedule_at (line 182) ... ok
[INFO] [stdout] test src/time/scheduler.rs - time::scheduler::Scheduler<M>::schedule_in (line 130) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::from_system (line 143) ... ok
[INFO] [stdout] test src/time/scheduler.rs - time::scheduler::Scheduler<M>::time (line 110) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::duration_since (line 337) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::checked_sub (line 290) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::new (line 103) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::subsec_nanos (line 231) ... ok
[INFO] [stdout] test src/simulation.rs - simulation (line 97) ... ok
[INFO] [stdout] test src/lib.rs - (line 166) ... ok
[INFO] [stdout] test src/lib.rs - (line 257) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out; finished in 0.83s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4f17fb76016e3f7b8c535516991c9c5cf0d2b22bb5a631c96cfbf9b4df5bbf7a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4f17fb76016e3f7b8c535516991c9c5cf0d2b22bb5a631c96cfbf9b4df5bbf7a", kill_on_drop: false }`
[INFO] [stdout] 4f17fb76016e3f7b8c535516991c9c5cf0d2b22bb5a631c96cfbf9b4df5bbf7a
