[INFO] fetching crate asynchronix 0.1.0...
[INFO] testing asynchronix-0.1.0 against beta-2023-04-20 for beta-1.70-1
[INFO] extracting crate asynchronix 0.1.0 into /workspace/builds/worker-2-tc2/source
[INFO] validating manifest of crates.io crate asynchronix 0.1.0 on toolchain beta-2023-04-20
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-04-20" "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-tc2/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" "+beta-2023-04-20" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded futures-executor v0.3.25
[INFO] [stderr]   Downloaded proc-macro2 v1.0.50
[INFO] [stderr]   Downloaded windows_aarch64_msvc v0.39.0
[INFO] [stderr]   Downloaded futures-macro v0.3.25
[INFO] [stderr]   Downloaded windows_i686_msvc v0.39.0
[INFO] [stderr]   Downloaded generator v0.7.2
[INFO] [stderr]   Downloaded loom v0.5.6
[INFO] [stderr]   Downloaded windows_i686_gnu v0.39.0
[INFO] [stderr]   Downloaded windows_x86_64_msvc v0.39.0
[INFO] [stderr]   Downloaded windows_x86_64_gnu v0.39.0
[INFO] [stderr]   Downloaded st3 v0.4.1
[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-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-04-20" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7369913631a04a37a6efe5828d2a0fc4c3e417fb9936839cfcc8837c026144a2
[INFO] running `Command { std: "docker" "start" "-a" "7369913631a04a37a6efe5828d2a0fc4c3e417fb9936839cfcc8837c026144a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7369913631a04a37a6efe5828d2a0fc4c3e417fb9936839cfcc8837c026144a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7369913631a04a37a6efe5828d2a0fc4c3e417fb9936839cfcc8837c026144a2", kill_on_drop: false }`
[INFO] [stdout] 7369913631a04a37a6efe5828d2a0fc4c3e417fb9936839cfcc8837c026144a2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=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" "+beta-2023-04-20" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b2bc406981b8672c86e0877bf56248d18f5a1147cd310e937b23804783b3f8f6
[INFO] running `Command { std: "docker" "start" "-a" "b2bc406981b8672c86e0877bf56248d18f5a1147cd310e937b23804783b3f8f6", kill_on_drop: false }`
[INFO] [stderr]    Compiling crossbeam-utils v0.8.14
[INFO] [stderr]    Compiling libc v0.2.139
[INFO] [stderr]    Compiling futures-task v0.3.25
[INFO] [stderr]    Compiling multishot v0.3.0
[INFO] [stderr]    Compiling diatomic-waker v0.1.0
[INFO] [stderr]    Compiling recycle-box v0.2.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.63s
[INFO] running `Command { std: "docker" "inspect" "b2bc406981b8672c86e0877bf56248d18f5a1147cd310e937b23804783b3f8f6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b2bc406981b8672c86e0877bf56248d18f5a1147cd310e937b23804783b3f8f6", kill_on_drop: false }`
[INFO] [stdout] b2bc406981b8672c86e0877bf56248d18f5a1147cd310e937b23804783b3f8f6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=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" "+beta-2023-04-20" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e3eb0435c4a5491cf56eb96d63d6a67684cc5c517ddf5b53b7f98e3dd202e449
[INFO] running `Command { std: "docker" "start" "-a" "e3eb0435c4a5491cf56eb96d63d6a67684cc5c517ddf5b53b7f98e3dd202e449", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.50
[INFO] [stderr]    Compiling quote v1.0.23
[INFO] [stderr]    Compiling unicode-ident v1.0.6
[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.12s
[INFO] running `Command { std: "docker" "inspect" "e3eb0435c4a5491cf56eb96d63d6a67684cc5c517ddf5b53b7f98e3dd202e449", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e3eb0435c4a5491cf56eb96d63d6a67684cc5c517ddf5b53b7f98e3dd202e449", kill_on_drop: false }`
[INFO] [stdout] e3eb0435c4a5491cf56eb96d63d6a67684cc5c517ddf5b53b7f98e3dd202e449
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=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" "+beta-2023-04-20" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0d04146a1be68a831a093e757b04206a9a6dc3bb7f9d9e754614cf0310d8a82f
[INFO] running `Command { std: "docker" "start" "-a" "0d04146a1be68a831a093e757b04206a9a6dc3bb7f9d9e754614cf0310d8a82f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/asynchronix-39d35f83494bc4a4)
[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_unscheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_cancel_mt ... ok
[INFO] [stdout] test executor::task::tests::general::task_cancel_scheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_promise_scheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_promise_mt ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_promise_unscheduled ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_cycle ... ok
[INFO] [stdout] test executor::task::tests::general::task_multiple_wake ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_runnable ... ok
[INFO] [stdout] test executor::task::tests::general::task_wake ... ok
[INFO] [stdout] test executor::task::tests::general::task_schedule_and_forget ... ok
[INFO] [stdout] test executor::task::tests::general::task_wake_and_forget ... ok
[INFO] [stdout] test executor::task::tests::general::task_schedule ... ok
[INFO] [stdout] test executor::task::tests::general::task_drop_runnable_mt ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_set ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_smoke ... ok
[INFO] [stdout] test executor::task::tests::general::task_wake_mt ... ok
[INFO] [stdout] test macros::scoped_thread_local::tests::scoped_local_key_unset ... ok
[INFO] [stdout] test executor::task::tests::general::task_multiple_wake_mt ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_extreme ... ok
[INFO] [stdout] test executor::tests::executor_deadlock_st ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_since_extreme ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_add_duration_with_carry ... ok
[INFO] [stdout] test executor::task::tests::general::task_schedule_mt ... ok
[INFO] [stdout] test executor::tests::executor_deadlock ... ok
[INFO] [stdout] test model::ports::broadcaster::tests::broadcast_event_smoke ... ok
[INFO] [stdout] test channel::queue::tests::queue_spsc_capacity_three ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_since_invalid ... ok
[INFO] [stdout] test executor::tests::executor_drop_cycle ... ok
[INFO] [stdout] test channel::queue::tests::queue_mpsc_capacity_three ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_from_system_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_extreme ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_ordering ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_since_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_smoke ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_sub_duration_with_carry ... ok
[INFO] [stdout] test util::priority_queue::tests::priority_queue_delete_invalid ... 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 time::monotonic_time::tests::time_equality ... ok
[INFO] [stdout] test util::slot::tests::slot_multi_threaded_drop_writer ... 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 macros::scoped_thread_local::tests::scoped_local_key_panic_resets ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_invalid - should panic ... ok
[INFO] [stdout] test util::slot::tests::slot_single_threaded_drop_writer ... 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 util::slot::tests::slot_multi_threaded_write ... ok
[INFO] [stdout] test time::monotonic_time::tests::time_duration_with_carry ... 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_single_threaded_write ... 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 channel::queue::tests::queue_spsc_capacity_two ... 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 8.16s
[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 77) ... ok
[INFO] [stdout] test src/model.rs - model (line 27) ... ok
[INFO] [stdout] test src/lib.rs - (line 80) ... ok
[INFO] [stdout] test src/model.rs - model (line 143) ... ok
[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/time/scheduler.rs - time::scheduler::Scheduler (line 34) ... ignored
[INFO] [stdout] test src/time.rs - time (line 17) ... ok
[INFO] [stdout] test src/lib.rs - (line 105) ... 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::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/monotonic_time.rs - time::monotonic_time::MonotonicTime::as_secs (line 168) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime (line 40) ... 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 (line 53) ... ok
[INFO] [stdout] test src/time/scheduler.rs - time::scheduler::Scheduler<M>::schedule_in (line 130) ... 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::checked_sub (line 290) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::from_system (line 143) ... 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::subsec_nanos (line 231) ... ok
[INFO] [stdout] test src/time/monotonic_time.rs - time::monotonic_time::MonotonicTime::new (line 103) ... 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.89s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "0d04146a1be68a831a093e757b04206a9a6dc3bb7f9d9e754614cf0310d8a82f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0d04146a1be68a831a093e757b04206a9a6dc3bb7f9d9e754614cf0310d8a82f", kill_on_drop: false }`
[INFO] [stdout] 0d04146a1be68a831a093e757b04206a9a6dc3bb7f9d9e754614cf0310d8a82f
