[INFO] fetching crate desync 0.9.0...
[INFO] testing desync-0.9.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate desync 0.9.0 into /workspace/builds/worker-0-tc2/source
[INFO] started tweaking crates.io crate desync 0.9.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate desync 0.9.0
[INFO] tweaked toml for crates.io crate desync 0.9.0 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate desync 0.9.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 desync 0.9.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] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:26:12
[INFO] [stderr]    |
[INFO] [stderr] 26 | homepage = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 31 | repository = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `desync` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462
[INFO] running `Command { std: "docker" "start" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", 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" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462" "/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" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", 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" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:26:12
[INFO] [stderr]    |
[INFO] [stderr] 26 | homepage = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 31 | repository = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `desync` (manifest) generated 1 warning
[INFO] [stderr]    Compiling proc-macro2 v1.0.95
[INFO] [stderr]    Compiling unicode-ident v1.0.18
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling libc v0.2.172
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.100
[INFO] [stderr]    Compiling num_cpus v1.16.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling desync v0.9.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 13.25s
[INFO] running `Command { std: "docker" "inspect" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", 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" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462" "/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] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:26:12
[INFO] [stderr]    |
[INFO] [stderr] 26 | homepage = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 31 | repository = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `desync` (manifest) generated 1 warning
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.100
[INFO] [stderr]    Compiling bumpalo v3.17.0
[INFO] [stderr]    Compiling log v0.4.27
[INFO] [stderr]    Compiling wasm-bindgen v0.2.100
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling wasm-bindgen-test-macro v0.3.50
[INFO] [stderr]    Compiling wasm-bindgen-backend v0.2.100
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.100
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.100
[INFO] [stderr]    Compiling js-sys v0.3.77
[INFO] [stderr]    Compiling wasm-bindgen-futures v0.4.50
[INFO] [stderr]    Compiling wasm-bindgen-test v0.3.50
[INFO] [stderr]    Compiling desync v0.9.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 26.90s
[INFO] running `Command { std: "docker" "inspect" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", 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" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:26:12
[INFO] [stderr]    |
[INFO] [stderr] 26 | homepage = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 31 | repository = "https://github.com/Logicalshift/desync"
[INFO] [stderr]    |              ----------------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `desync` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/desync/b58bbe9e6d06bf7c/out/desync-b58bbe9e6d06bf7c)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test scheduler::scheduler_future::test::cancels_when_dropped ... ok
[INFO] [stdout] test scheduler::scheduler_future::test::returns_immediately_if_signaled ... ok
[INFO] [stderr]      Running tests/desync.rs (/opt/rustwide/target/release/build/desync/72d1336dd6435c66/out/desync-72d1336dd6435c66)
[INFO] [stdout] test scheduler::scheduler_future::test::forces_queue_drain ... ok
[INFO] [stdout] test scheduler::scheduler_future::test::signals_from_another_thread ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test retrieve_data_into_local_var ... ok
[INFO] [stdout] test retrieve_data_synchronously ... ok
[INFO] [stdout] test scheduler::future_desync::poll_two_futures_on_one_queue ... ok
[INFO] [stdout] test scheduler::asynchronous::schedule_async ... ok
[INFO] [stdout] test scheduler::asynchronous::panicking_panics_with_future_queues - should panic ... FAILED
[INFO] [stdout] test scheduler::asynchronous::schedule_after_queue_released ... ok
[INFO] [stdout] test scheduler::future_sync::poll_two_futures_on_one_queue ... ok
[INFO] [stdout] test scheduler::future_desync::wait_for_desync_future_from_desync_future ... ok
[INFO] [stdout] test scheduler::future_desync::read_sync_result ... ok
[INFO] [stdout] test scheduler::asynchronous::will_schedule_separate_queues_in_parallel ... ok
[INFO] [stdout] test scheduler::future_desync::schedule_future ... ok
[INFO] [stdout] test scheduler::future_desync::future_waits_for_us ... ok
[INFO] [stdout] test scheduler::future_desync::schedule_future_with_no_scheduler_threads ... ok
[INFO] [stdout] test scheduler::asynchronous::will_schedule_in_order ... ok
[INFO] [stdout] test scheduler::future_desync::wait_for_future ... ok
[INFO] [stdout] test scheduler::future_sync::future_waits_for_us ... ok
[INFO] [stdout] test scheduler::suspend::safe_to_drop_suspended_queue ... ok
[INFO] [stdout] test scheduler::future_desync::wake_future_with_no_scheduler_threads ... ok
[INFO] [stdout] test scheduler::sync::reschedule_after_immediate_sync ... ok
[INFO] [stdout] test scheduler::sync::schedule_sync ... ok
[INFO] [stdout] test scheduler::future_sync::schedule_future ... ok
[INFO] [stdout] test dropping_while_running_isnt_obviously_bad ... ok
[INFO] [stdout] test scheduler::sync::sync_has_synchronous_lifetime ... ok
[INFO] [stdout] test scheduler::future_sync::schedule_future_with_no_scheduler_threads ... ok
[INFO] [stdout] test scheduler::sync::try_sync_fails_on_busy_queue ... ok
[INFO] [stdout] test scheduler::sync::try_sync_succeeds_on_idle_queue ... ok
[INFO] [stdout] test scheduler::future_sync::wait_for_future ... ok
[INFO] [stdout] test try_sync_fails_on_busy_queue ... ok
[INFO] [stdout] test try_sync_succeeds_on_idle_queue ... ok
[INFO] [stdout] test scheduler::future_sync::wake_future_with_no_scheduler_threads ... ok
[INFO] [stdout] test scheduler::suspend::suspend_queue_with_local_drain ... ok
[INFO] [stdout] test scheduler::sync::schedule_sync_after_async ... ok
[INFO] [stdout] test scheduler::sync::sync_drains_with_no_threads ... ok
[INFO] [stdout] test scheduler::future_sync::wait_for_desync_future_from_sync_future ... ok
[INFO] [stdout] test scheduler::sync::try_sync_succeeds_on_idle_queue_after_async_job ... ok
[INFO] [stdout] test try_sync_succeeds_on_idle_queue_after_async_job ... ok
[INFO] [stdout] test scheduler::thread_management::will_despawn_extra_threads ... ok
[INFO] [stdout] test scheduler::future_sync::wait_for_sync_future_from_sync_future ... ok
[INFO] [stdout] test scheduler::future_sync::wait_for_sync_future_from_desync_future ... ok
[INFO] [stdout] test update_data_asynchronously ... ok
[INFO] [stdout] test update_data_with_future ... ok
[INFO] [stdout] test update_data_with_future_sync ... ok
[INFO] [stdout] test wait_for_future ... ok
[INFO] [stdout] test scheduler::future_sync::wait_for_sync_future_from_desync_future_without_awaiting ... ok
[INFO] [stdout] test double_future_and_sync ... ok
[INFO] [stdout] test future_and_sync ... ok
[INFO] [stdout] test scheduler::asynchronous::async_only_runs_once ... FAILED
[INFO] [stdout] test update_data_asynchronously_1000_times ... ok
[INFO] [stdout] test update_data_with_future_1000_times ... ok
[INFO] [stdout] test update_data_with_future_sync_1000_times ... ok
[INFO] [stdout] test scheduler::asynchronous::async_runs_in_order_1000_iter ... ok
[INFO] [stdout] test scheduler::suspend::suspend_queue_with_background_thread ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- scheduler::asynchronous::panicking_panics_with_future_queues stdout ----
[INFO] [stdout] note: test did not panic as expected at tests/scheduler/asynchronous.rs:26:4
[INFO] [stdout] ---- scheduler::asynchronous::async_only_runs_once stdout ----
[INFO] [stdout] IIIIIIIIII Pending queue count: 0
[INFO] [stdout] 
[INFO] [stdout] thread 'scheduler::asynchronous::async_only_runs_once' (946) panicked at tests/scheduler/timeout.rs:57:13:
[INFO] [stdout] Timeout
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61460a0daf51 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x61460a0eef3a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x61460a0df23c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x61460a0b6ec6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x61460a0d33a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x61460a05bb20 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x61460a0d3562 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x61460a0b655c - std[617be2ca0a6fd877]::panicking::begin_panic::<&str>::{closure#0}
[INFO] [stdout]    8:     0x61460a0afc39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::begin_panic<&str>::{closure#0}, !>
[INFO] [stdout]    9:     0x61460a0ad38b - std[617be2ca0a6fd877]::panicking::begin_panic::<&str>
[INFO] [stdout]   10:     0x61460a003ffc - desync[4dafd0d42cada5db]::scheduler::timeout::timeout::<desync[4dafd0d42cada5db]::scheduler::asynchronous::async_only_runs_once::{closure#0}>
[INFO] [stdout]   11:     0x61460a03b34a - <desync[4dafd0d42cada5db]::scheduler::asynchronous::async_only_runs_once::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   12:     0x61460a04ef6b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   13:     0x61460a05c145 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   14:     0x61460a055a34 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   15:     0x61460a05f1d2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   16:     0x61460a0da77f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   17:     0x7fdc6e449dfa - <unknown>
[INFO] [stdout]   18:     0x7fdc6e4dd3d4 - clone
[INFO] [stdout]   19:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     scheduler::asynchronous::async_only_runs_once
[INFO] [stdout]     scheduler::asynchronous::panicking_panics_with_future_queues
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 50 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.20s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test desync`
[INFO] running `Command { std: "docker" "inspect" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462", kill_on_drop: false }`
[INFO] [stdout] efed15328ee5d20562e79b1e7c7e744f652e9eda2348e0144e076bdcc3347462
