[INFO] fetching crate desync 0.9.0... [INFO] testing desync-0.9.0 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1 [INFO] extracting crate desync 0.9.0 into /workspace/builds/worker-0-tc1/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-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate desync 0.9.0 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "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" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "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-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 9b9ba88cc57cfa02f814b510642a08d7e19797c9e8ad7b92fc13bb770759e310 [INFO] running `Command { std: "docker" "start" "-a" "9b9ba88cc57cfa02f814b510642a08d7e19797c9e8ad7b92fc13bb770759e310", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "9b9ba88cc57cfa02f814b510642a08d7e19797c9e8ad7b92fc13bb770759e310", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9b9ba88cc57cfa02f814b510642a08d7e19797c9e8ad7b92fc13bb770759e310", kill_on_drop: false }` [INFO] [stdout] 9b9ba88cc57cfa02f814b510642a08d7e19797c9e8ad7b92fc13bb770759e310 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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=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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 8218f6c8df6e98cf366e3aff0a5cd16997dcc4eed4528b560a25d3c12a61b89c [INFO] running `Command { std: "docker" "start" "-a" "8218f6c8df6e98cf366e3aff0a5cd16997dcc4eed4528b560a25d3c12a61b89c", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.95 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling libc v0.2.172 [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 `dev` profile [unoptimized + debuginfo] target(s) in 10.46s [INFO] running `Command { std: "docker" "inspect" "8218f6c8df6e98cf366e3aff0a5cd16997dcc4eed4528b560a25d3c12a61b89c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8218f6c8df6e98cf366e3aff0a5cd16997dcc4eed4528b560a25d3c12a61b89c", kill_on_drop: false }` [INFO] [stdout] 8218f6c8df6e98cf366e3aff0a5cd16997dcc4eed4528b560a25d3c12a61b89c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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=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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] a87998203a294595ca5c249293943aaa18caa4dc731fa293f30e5118ebf5fd23 [INFO] running `Command { std: "docker" "start" "-a" "a87998203a294595ca5c249293943aaa18caa4dc731fa293f30e5118ebf5fd23", kill_on_drop: false }` [INFO] [stderr] Compiling wasm-bindgen-shared v0.2.100 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling bumpalo v3.17.0 [INFO] [stderr] Compiling wasm-bindgen v0.2.100 [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 `test` profile [unoptimized + debuginfo] target(s) in 15.97s [INFO] running `Command { std: "docker" "inspect" "a87998203a294595ca5c249293943aaa18caa4dc731fa293f30e5118ebf5fd23", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a87998203a294595ca5c249293943aaa18caa4dc731fa293f30e5118ebf5fd23", kill_on_drop: false }` [INFO] [stdout] a87998203a294595ca5c249293943aaa18caa4dc731fa293f30e5118ebf5fd23 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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=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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 0a8eababc320383694a336bd09099663efc98d072d19037cd5ab8a49cd8e67c5 [INFO] running `Command { std: "docker" "start" "-a" "0a8eababc320383694a336bd09099663efc98d072d19037cd5ab8a49cd8e67c5", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/desync-b6908bf9d41803a8) [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] [stdout] test scheduler::scheduler_future::test::forces_queue_drain ... ok [INFO] [stdout] test scheduler::scheduler_future::test::signals_from_another_thread ... ok [INFO] [stderr] Running tests/desync.rs (/opt/rustwide/target/debug/deps/desync-3aea5ae65498fcb4) [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_synchronously ... ok [INFO] [stdout] test retrieve_data_into_local_var ... ok [INFO] [stdout] test scheduler::future_desync::poll_two_futures_on_one_queue ... ok [INFO] [stdout] test scheduler::asynchronous::schedule_after_queue_released ... 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::future_sync::poll_two_futures_on_one_queue ... ok [INFO] [stdout] test scheduler::asynchronous::will_schedule_in_order ... 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::future_desync::wait_for_future ... ok [INFO] [stdout] test scheduler::asynchronous::will_schedule_separate_queues_in_parallel ... ok [INFO] [stdout] test scheduler::future_sync::future_waits_for_us ... ok [INFO] [stdout] test scheduler::future_desync::wake_future_with_no_scheduler_threads ... ok [INFO] [stdout] test scheduler::future_desync::read_sync_result ... ok [INFO] [stdout] test scheduler::future_sync::schedule_future ... ok [INFO] [stdout] test scheduler::suspend::safe_to_drop_suspended_queue ... ok [INFO] [stdout] test scheduler::future_desync::schedule_future ... ok [INFO] [stdout] test dropping_while_running_isnt_obviously_bad ... ok [INFO] [stdout] test scheduler::sync::schedule_sync ... ok [INFO] [stdout] test scheduler::future_sync::wait_for_future ... ok [INFO] [stdout] test scheduler::sync::reschedule_after_immediate_sync ... ok [INFO] [stdout] test scheduler::future_sync::schedule_future_with_no_scheduler_threads ... ok [INFO] [stdout] test scheduler::sync::sync_has_synchronous_lifetime ... ok [INFO] [stdout] test scheduler::sync::try_sync_succeeds_on_idle_queue ... ok [INFO] [stdout] test scheduler::sync::try_sync_fails_on_busy_queue ... ok [INFO] [stdout] test scheduler::future_sync::wake_future_with_no_scheduler_threads ... 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::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::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 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 ... FAILED [INFO] [stdout] test scheduler::future_desync::wait_for_desync_future_from_desync_future ... ok [INFO] [stdout] test double_future_and_sync ... ok [INFO] [stdout] test scheduler::future_sync::wait_for_desync_future_from_sync_future ... ok [INFO] [stdout] test scheduler::future_sync::wait_for_sync_future_from_sync_future ... ok [INFO] [stdout] test future_and_sync ... ok [INFO] [stdout] test scheduler::asynchronous::async_only_runs_once ... FAILED [INFO] [stdout] test scheduler::future_sync::wait_for_sync_future_from_desync_future ... ok [INFO] [stdout] test scheduler::thread_management::will_despawn_extra_threads ... ok [INFO] [stdout] test scheduler::asynchronous::async_runs_in_order_1000_iter ... ok [INFO] [stdout] test update_data_with_future_1000_times ... ok [INFO] [stdout] test update_data_asynchronously_1000_times ... ok [INFO] [stdout] test update_data_with_future_sync_1000_times ... 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::future_sync::wait_for_sync_future_from_desync_future_without_awaiting stdout ---- [INFO] [stdout] BBBBBBBBIIIIIIIIIIIIIIII Pending queue count: 0 [INFO] [stdout] [INFO] [stdout] thread 'scheduler::future_sync::wait_for_sync_future_from_desync_future_without_awaiting' (438) panicked at tests/scheduler/timeout.rs:57:13: [INFO] [stdout] Timeout [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x627f7d21ec42 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x627f7d21ec42 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x627f7d21ec42 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x627f7d21ec42 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x627f7d22ef4f - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x627f7d22ef4f - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x627f7d1ebfa3 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x627f7d1ebfa3 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x627f7d1f7e42 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x627f7d1fcc9f - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x627f7d1fcb31 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x627f7d180cae - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x627f7d180cae - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x627f7d1fd33e - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x627f7d1fd33e - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x627f7d1fc9ac - std::panicking::begin_panic::{{closure}}::h9a170b14ab031998 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:771:9 [INFO] [stdout] 16: 0x627f7d1f7f89 - std::sys::backtrace::__rust_end_short_backtrace::hcaa465788ea2ec91 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x627f7d1fc97c - std::panicking::begin_panic::hd649790486a16e6e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:770:5 [INFO] [stdout] 18: 0x627f7d102bc6 - desync::scheduler::timeout::timeout::h055f777ed799b13d [INFO] [stdout] at /opt/rustwide/workdir/tests/scheduler/timeout.rs:57:13 [INFO] [stdout] 19: 0x627f7d1396cb - desync::scheduler::future_sync::wait_for_sync_future_from_desync_future_without_awaiting::hc8c9ca9a1bf72a09 [INFO] [stdout] at /opt/rustwide/workdir/tests/scheduler/future_sync.rs:222:5 [INFO] [stdout] 20: 0x627f7d1396e7 - desync::scheduler::future_sync::wait_for_sync_future_from_desync_future_without_awaiting::{{closure}}::h52ab32a221037d81 [INFO] [stdout] at /opt/rustwide/workdir/tests/scheduler/future_sync.rs:221:62 [INFO] [stdout] 21: 0x627f7d0a0036 - core::ops::function::FnOnce::call_once::h14de92cc8ea6de26 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 22: 0x627f7d180aab - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x627f7d180aab - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 24: 0x627f7d196555 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 25: 0x627f7d196555 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x627f7d196555 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 27: 0x627f7d196555 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 28: 0x627f7d196555 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 29: 0x627f7d196555 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 30: 0x627f7d196555 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 31: 0x627f7d16cf74 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stdout] 32: 0x627f7d16cf74 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 33: 0x627f7d17085a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 34: 0x627f7d17085a - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x627f7d17085a - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 36: 0x627f7d17085a - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 37: 0x627f7d17085a - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 38: 0x627f7d17085a - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 39: 0x627f7d17085a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 40: 0x627f7d1f211f - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 41: 0x627f7d1f211f - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 42: 0x7e11b7e4baa4 - [INFO] [stdout] 43: 0x7e11b7ed8a34 - clone [INFO] [stdout] 44: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- scheduler::asynchronous::async_only_runs_once stdout ---- [INFO] [stdout] BIIIIIIIIIIIIIIIIIIIIIII Pending queue count: 0 [INFO] [stdout] [INFO] [stdout] thread 'scheduler::asynchronous::async_only_runs_once' (36) panicked at tests/scheduler/timeout.rs:57:13: [INFO] [stdout] Timeout [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x627f7d21ec42 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x627f7d21ec42 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x627f7d21ec42 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x627f7d21ec42 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x627f7d22ef4f - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x627f7d22ef4f - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x627f7d1ebfa3 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x627f7d1ebfa3 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x627f7d1f7e42 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x627f7d1fcc9f - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x627f7d1fcb31 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x627f7d180cae - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x627f7d180cae - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x627f7d1fd33e - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x627f7d1fd33e - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x627f7d1fc9ac - std::panicking::begin_panic::{{closure}}::h9a170b14ab031998 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:771:9 [INFO] [stdout] 16: 0x627f7d1f7f89 - std::sys::backtrace::__rust_end_short_backtrace::hcaa465788ea2ec91 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x627f7d1fc97c - std::panicking::begin_panic::hd649790486a16e6e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:770:5 [INFO] [stdout] 18: 0x627f7d10d906 - desync::scheduler::timeout::timeout::h8d1b503c293498ce [INFO] [stdout] at /opt/rustwide/workdir/tests/scheduler/timeout.rs:57:13 [INFO] [stdout] 19: 0x627f7d13a248 - desync::scheduler::asynchronous::async_only_runs_once::h8ea24da4dad1d186 [INFO] [stdout] at /opt/rustwide/workdir/tests/scheduler/asynchronous.rs:46:9 [INFO] [stdout] 20: 0x627f7d13a6c7 - desync::scheduler::asynchronous::async_only_runs_once::{{closure}}::hf8ea2483580ea70a [INFO] [stdout] at /opt/rustwide/workdir/tests/scheduler/asynchronous.rs:44:26 [INFO] [stdout] 21: 0x627f7d0a0b26 - core::ops::function::FnOnce::call_once::he32f17f480d12629 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 22: 0x627f7d180aab - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x627f7d180aab - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 24: 0x627f7d196555 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 25: 0x627f7d196555 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x627f7d196555 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 27: 0x627f7d196555 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 28: 0x627f7d196555 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 29: 0x627f7d196555 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 30: 0x627f7d196555 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 31: 0x627f7d16cf74 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stdout] 32: 0x627f7d16cf74 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 33: 0x627f7d17085a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 34: 0x627f7d17085a - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x627f7d17085a - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 36: 0x627f7d17085a - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 37: 0x627f7d17085a - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 38: 0x627f7d17085a - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 39: 0x627f7d17085a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 40: 0x627f7d1f211f - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 41: 0x627f7d1f211f - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 42: 0x7e11b7e4baa4 - [INFO] [stdout] 43: 0x7e11b7ed8a34 - clone [INFO] [stdout] 44: 0x0 - [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] scheduler::future_sync::wait_for_sync_future_from_desync_future_without_awaiting [INFO] [stdout] [INFO] [stdout] test result: FAILED. 49 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.27s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test desync` [INFO] running `Command { std: "docker" "inspect" "0a8eababc320383694a336bd09099663efc98d072d19037cd5ab8a49cd8e67c5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0a8eababc320383694a336bd09099663efc98d072d19037cd5ab8a49cd8e67c5", kill_on_drop: false }` [INFO] [stdout] 0a8eababc320383694a336bd09099663efc98d072d19037cd5ab8a49cd8e67c5