[INFO] fetching crate futex-queue 0.1.1... [INFO] testing futex-queue-0.1.1 against try#61cd42370816fd135c0f3b9debf2f5dfcf194594 for pr-98839 [INFO] extracting crate futex-queue 0.1.1 into /workspace/builds/worker-0/source [INFO] validating manifest of crates.io crate futex-queue 0.1.1 on toolchain 61cd42370816fd135c0f3b9debf2f5dfcf194594 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate futex-queue 0.1.1 [INFO] finished tweaking crates.io crate futex-queue 0.1.1 [INFO] tweaked toml for crates.io crate futex-queue 0.1.1 written to /workspace/builds/worker-0/source/Cargo.toml [INFO] crate crates.io crate futex-queue 0.1.1 already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] a2bd76735a180625a411fcbd0489e98d98f5a5e6cb7fec581b2917a95be6727a [INFO] running `Command { std: "docker" "start" "-a" "a2bd76735a180625a411fcbd0489e98d98f5a5e6cb7fec581b2917a95be6727a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "a2bd76735a180625a411fcbd0489e98d98f5a5e6cb7fec581b2917a95be6727a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a2bd76735a180625a411fcbd0489e98d98f5a5e6cb7fec581b2917a95be6727a", kill_on_drop: false }` [INFO] [stdout] a2bd76735a180625a411fcbd0489e98d98f5a5e6cb7fec581b2917a95be6727a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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" "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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 90cf94e0e096422dc1278a3e7ee88559ce29f15b7f0c54cb5df6098a82ba714a [INFO] running `Command { std: "docker" "start" "-a" "90cf94e0e096422dc1278a3e7ee88559ce29f15b7f0c54cb5df6098a82ba714a", kill_on_drop: false }` [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling libc v0.2.102 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling heapless v0.7.7 [INFO] [stderr] Compiling stable_deref_trait v1.2.0 [INFO] [stderr] Compiling lock_api v0.4.5 [INFO] [stderr] Compiling hash32 v0.2.1 [INFO] [stderr] Compiling spin v0.9.2 [INFO] [stderr] Compiling linux-futex v0.1.1 [INFO] [stderr] Compiling futex-queue v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.80s [INFO] running `Command { std: "docker" "inspect" "90cf94e0e096422dc1278a3e7ee88559ce29f15b7f0c54cb5df6098a82ba714a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "90cf94e0e096422dc1278a3e7ee88559ce29f15b7f0c54cb5df6098a82ba714a", kill_on_drop: false }` [INFO] [stdout] 90cf94e0e096422dc1278a3e7ee88559ce29f15b7f0c54cb5df6098a82ba714a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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" "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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] af92cc3f661ff533a07ab5b2b2af40a21eaee05f63e8daaef4d0d4b847127459 [INFO] running `Command { std: "docker" "start" "-a" "af92cc3f661ff533a07ab5b2b2af40a21eaee05f63e8daaef4d0d4b847127459", kill_on_drop: false }` [INFO] [stderr] Compiling futex-queue v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.76s [INFO] running `Command { std: "docker" "inspect" "af92cc3f661ff533a07ab5b2b2af40a21eaee05f63e8daaef4d0d4b847127459", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "af92cc3f661ff533a07ab5b2b2af40a21eaee05f63e8daaef4d0d4b847127459", kill_on_drop: false }` [INFO] [stdout] af92cc3f661ff533a07ab5b2b2af40a21eaee05f63e8daaef4d0d4b847127459 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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" "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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] a08bb8c96a07639741ed7c820d5945b3c65c5aa248a6969ae4ca24e91a9577a7 [INFO] running `Command { std: "docker" "start" "-a" "a08bb8c96a07639741ed7c820d5945b3c65c5aa248a6969ae4ca24e91a9577a7", kill_on_drop: false }` [INFO] [stdout] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stdout] running 0 tests [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/futex_queue-f7c84c38aeb28679) [INFO] [stdout] [INFO] [stderr] Running tests/test.rs (/opt/rustwide/target/debug/deps/test-f356ff65074f871e) [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test simple_send_recv ... ok [INFO] [stdout] test sorting ... ok [INFO] [stdout] test timing ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- timing stdout ---- [INFO] [stdout] thread 'timing' panicked at 'assertion failed: Instant::now() - later < Duration::from_micros(100)', tests/test.rs:45:5 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x560337c5ba30 - std::backtrace_rs::backtrace::libunwind::trace::h385ffbf574363e81 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 1: 0x560337c5ba30 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1dbabbc7babee7 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x560337c5ba30 - std::sys_common::backtrace::_print_fmt::h3fd7a321b650d311 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys_common/backtrace.rs:66:5 [INFO] [stdout] 3: 0x560337c5ba30 - ::fmt::h9c5fc513eb6f6c7a [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys_common/backtrace.rs:45:22 [INFO] [stdout] 4: 0x560337c7e6ac - core::fmt::write::h149259a69b3e2f89 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/fmt/mod.rs:1198:17 [INFO] [stdout] 5: 0x560337c58385 - std::io::Write::write_fmt::h6d11f4b16fd2c9e6 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/io/mod.rs:1672:15 [INFO] [stdout] 6: 0x560337c5d471 - std::sys_common::backtrace::_print::h350a49f9ba10dc38 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys_common/backtrace.rs:48:5 [INFO] [stdout] 7: 0x560337c5d471 - std::sys_common::backtrace::print::hc9483bcde74add9a [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys_common/backtrace.rs:35:9 [INFO] [stdout] 8: 0x560337c5d471 - std::panicking::default_hook::{{closure}}::h033c319db21062da [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:295:22 [INFO] [stdout] 9: 0x560337c5d0c6 - std::panicking::default_hook::h082702b84ddf9cba [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:311:9 [INFO] [stdout] 10: 0x560337c5db13 - std::panicking::rust_panic_with_hook::hb5031567964b2362 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:698:17 [INFO] [stdout] 11: 0x560337c5d9c9 - std::panicking::begin_panic_handler::{{closure}}::h040f81592aed215a [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:586:13 [INFO] [stdout] 12: 0x560337c5bf04 - std::sys_common::backtrace::__rust_end_short_backtrace::hb55752442d66befe [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys_common/backtrace.rs:138:18 [INFO] [stdout] 13: 0x560337c5d732 - rust_begin_unwind [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:584:5 [INFO] [stdout] 14: 0x560337bf8d23 - core::panicking::panic_fmt::hab8e33512c332233 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/panicking.rs:142:14 [INFO] [stdout] 15: 0x560337bf8bed - core::panicking::panic::hf97f736a376a5fe9 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/panicking.rs:48:5 [INFO] [stdout] 16: 0x560337bfbb98 - test::timing::h59e6f0f03742fa5f [INFO] [stdout] at /opt/rustwide/workdir/tests/test.rs:45:5 [INFO] [stdout] 17: 0x560337bfb96a - test::timing::{{closure}}::he633eb77953b5350 [INFO] [stdout] at /opt/rustwide/workdir/tests/test.rs:38:1 [INFO] [stdout] 18: 0x560337bfef2e - core::ops::function::FnOnce::call_once::hb7c242a48c95a14b [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 19: 0x560337c35a23 - core::ops::function::FnOnce::call_once::ha7afe1ddf9a8665b [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 20: 0x560337c35a23 - test::__rust_begin_short_backtrace::h02df713062fe0d09 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/test/src/lib.rs:572:5 [INFO] [stdout] 21: 0x560337c3481c - as core::ops::function::FnOnce>::call_once::h4f2b36c78ff85792 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 22: 0x560337c3481c - as core::ops::function::FnOnce<()>>::call_once::h8624c6094a076c25 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 23: 0x560337c3481c - std::panicking::try::do_call::h0c501a0f7fa5bee0 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:492:40 [INFO] [stdout] 24: 0x560337c3481c - std::panicking::try::h60ab49b272d3e4f0 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:456:19 [INFO] [stdout] 25: 0x560337c3481c - std::panic::catch_unwind::h5f34d55961997ae8 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panic.rs:137:14 [INFO] [stdout] 26: 0x560337c3481c - test::run_test_in_process::hcf99044408a39ffa [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/test/src/lib.rs:595:18 [INFO] [stdout] 27: 0x560337c3481c - test::run_test::run_test_inner::{{closure}}::h5f747e7c34969277 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/test/src/lib.rs:489:39 [INFO] [stdout] 28: 0x560337c0078c - test::run_test::run_test_inner::{{closure}}::hfbc9b7f814e7611c [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/test/src/lib.rs:516:37 [INFO] [stdout] 29: 0x560337c0078c - std::sys_common::backtrace::__rust_begin_short_backtrace::hc864e8be9763567e [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys_common/backtrace.rs:122:18 [INFO] [stdout] 30: 0x560337c0669f - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h635fae7f98a9851a [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/thread/mod.rs:505:17 [INFO] [stdout] 31: 0x560337c0669f - as core::ops::function::FnOnce<()>>::call_once::hb0e77e0fbf0b74e7 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 32: 0x560337c0669f - std::panicking::try::do_call::h843a2c7f433b6950 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:492:40 [INFO] [stdout] 33: 0x560337c0669f - std::panicking::try::h64dfa5d8b1ef556f [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panicking.rs:456:19 [INFO] [stdout] 34: 0x560337c0669f - std::panic::catch_unwind::h3af2f5bf220b9738 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/panic.rs:137:14 [INFO] [stdout] 35: 0x560337c0669f - std::thread::Builder::spawn_unchecked_::{{closure}}::h8fd0a07746213f59 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/thread/mod.rs:504:30 [INFO] [stdout] 36: 0x560337c0669f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h589aaf080555aafb [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 37: 0x560337c62083 - as core::ops::function::FnOnce>::call_once::h4a4c573311170bd5 [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 38: 0x560337c62083 - as core::ops::function::FnOnce>::call_once::h4c3a154b8915668e [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 39: 0x560337c62083 - std::sys::unix::thread::Thread::new::thread_start::h9d4e5992c5fda7ce [INFO] [stdout] at /rustc/61cd42370816fd135c0f3b9debf2f5dfcf194594/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 40: 0x7f37b6e9e609 - start_thread [INFO] [stdout] 41: 0x7f37b6c6e133 - clone [INFO] [stdout] 42: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] timing [INFO] [stdout] [INFO] [stdout] test result: FAILED. 2 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.01s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass '--test test' [INFO] running `Command { std: "docker" "inspect" "a08bb8c96a07639741ed7c820d5945b3c65c5aa248a6969ae4ca24e91a9577a7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a08bb8c96a07639741ed7c820d5945b3c65c5aa248a6969ae4ca24e91a9577a7", kill_on_drop: false }` [INFO] [stdout] a08bb8c96a07639741ed7c820d5945b3c65c5aa248a6969ae4ca24e91a9577a7