[INFO] fetching crate pausable_clock 1.0.1... [INFO] testing pausable_clock-1.0.1 against try#5bc97a20e5cc382ae6d19f727a9e73eeb2734548 for pr-99093 [INFO] extracting crate pausable_clock 1.0.1 into /workspace/builds/worker-7/source [INFO] validating manifest of crates.io crate pausable_clock 1.0.1 on toolchain 5bc97a20e5cc382ae6d19f727a9e73eeb2734548 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate pausable_clock 1.0.1 [INFO] finished tweaking crates.io crate pausable_clock 1.0.1 [INFO] tweaked toml for crates.io crate pausable_clock 1.0.1 written to /workspace/builds/worker-7/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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:ecb52c4cf6672b83cc996368a05b4a059042425f2b6ba9cd23a06cdead5d8d70" "/opt/rustwide/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 2ab3993999986732601811f3c7a1e7caf5c4c8a6cea020473bdd7f401cdb4efe [INFO] running `Command { std: "docker" "start" "-a" "2ab3993999986732601811f3c7a1e7caf5c4c8a6cea020473bdd7f401cdb4efe", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2ab3993999986732601811f3c7a1e7caf5c4c8a6cea020473bdd7f401cdb4efe", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2ab3993999986732601811f3c7a1e7caf5c4c8a6cea020473bdd7f401cdb4efe", kill_on_drop: false }` [INFO] [stdout] 2ab3993999986732601811f3c7a1e7caf5c4c8a6cea020473bdd7f401cdb4efe [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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:ecb52c4cf6672b83cc996368a05b4a059042425f2b6ba9cd23a06cdead5d8d70" "/opt/rustwide/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 120a5006aa8c0e2981a8c40ac895994cd4b101b095c7fd5535dbbb85610ee44b [INFO] running `Command { std: "docker" "start" "-a" "120a5006aa8c0e2981a8c40ac895994cd4b101b095c7fd5535dbbb85610ee44b", kill_on_drop: false }` [INFO] [stderr] Compiling pausable_clock v1.0.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.31s [INFO] running `Command { std: "docker" "inspect" "120a5006aa8c0e2981a8c40ac895994cd4b101b095c7fd5535dbbb85610ee44b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "120a5006aa8c0e2981a8c40ac895994cd4b101b095c7fd5535dbbb85610ee44b", kill_on_drop: false }` [INFO] [stdout] 120a5006aa8c0e2981a8c40ac895994cd4b101b095c7fd5535dbbb85610ee44b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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:ecb52c4cf6672b83cc996368a05b4a059042425f2b6ba9cd23a06cdead5d8d70" "/opt/rustwide/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b35c3b811b6ba48dd90a7300b45f1c04bacdbf75906e416b4b7bc67d1dd32062 [INFO] running `Command { std: "docker" "start" "-a" "b35c3b811b6ba48dd90a7300b45f1c04bacdbf75906e416b4b7bc67d1dd32062", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling serde v1.0.139 [INFO] [stderr] Compiling crossbeam-utils v0.8.10 [INFO] [stderr] Compiling once_cell v1.13.0 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling libc v0.2.126 [INFO] [stderr] Compiling proc-macro2 v1.0.40 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling quote v1.0.20 [INFO] [stderr] Compiling regex-syntax v0.6.27 [INFO] [stderr] Compiling rayon-core v1.9.3 [INFO] [stderr] Compiling unicode-ident v1.0.2 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling ryu v1.0.10 [INFO] [stderr] Compiling either v1.7.0 [INFO] [stderr] Compiling syn v1.0.98 [INFO] [stderr] Compiling serde_json v1.0.82 [INFO] [stderr] Compiling itoa v1.0.2 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling unicode-width v0.1.9 [INFO] [stderr] Compiling plotters-backend v0.3.4 [INFO] [stderr] Compiling serde_derive v1.0.139 [INFO] [stderr] Compiling itoa v0.4.8 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling cast v0.3.0 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling half v1.8.2 [INFO] [stderr] Compiling oorandom v11.1.3 [INFO] [stderr] Compiling itertools v0.10.3 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling plotters-svg v0.3.2 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling memoffset v0.6.5 [INFO] [stderr] Compiling crossbeam-epoch v0.9.9 [INFO] [stderr] Compiling num-traits v0.2.15 [INFO] [stderr] Compiling rayon v1.5.3 [INFO] [stderr] Compiling crossbeam-channel v0.5.5 [INFO] [stderr] Compiling csv-core v0.1.10 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.6.0 [INFO] [stderr] Compiling criterion-plot v0.4.5 [INFO] [stderr] Compiling crossbeam-deque v0.8.1 [INFO] [stderr] Compiling plotters v0.3.2 [INFO] [stderr] Compiling bstr v0.2.17 [INFO] [stderr] Compiling serde_cbor v0.11.2 [INFO] [stderr] Compiling csv v1.1.6 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling criterion v0.3.6 [INFO] [stderr] Compiling pausable_clock v1.0.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 22.54s [INFO] running `Command { std: "docker" "inspect" "b35c3b811b6ba48dd90a7300b45f1c04bacdbf75906e416b4b7bc67d1dd32062", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b35c3b811b6ba48dd90a7300b45f1c04bacdbf75906e416b4b7bc67d1dd32062", kill_on_drop: false }` [INFO] [stdout] b35c3b811b6ba48dd90a7300b45f1c04bacdbf75906e416b4b7bc67d1dd32062 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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:ecb52c4cf6672b83cc996368a05b4a059042425f2b6ba9cd23a06cdead5d8d70" "/opt/rustwide/cargo-home/bin/cargo" "+5bc97a20e5cc382ae6d19f727a9e73eeb2734548" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] adc11781f47e11216dcaed55152c3687cd47568a5b6bee5829f5a5b4e3bb2c1e [INFO] running `Command { std: "docker" "start" "-a" "adc11781f47e11216dcaed55152c3687cd47568a5b6bee5829f5a5b4e3bb2c1e", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.05s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pausable_clock-7c097c8550a262b3) [INFO] [stdout] [INFO] [stdout] running 14 tests [INFO] [stdout] test pausable_instant::tests::test_duration_since ... ok [INFO] [stdout] test pausable_instant::tests::test_checked_sub ... ok [INFO] [stdout] test pause_state::tests::test_accurate_millis ... ok [INFO] [stdout] test pausable_instant::tests::test_checked_add ... ok [INFO] [stdout] test pausable_instant::tests::test_elapsed_fail - should panic ... ok [INFO] [stdout] test pausable_instant::tests::test_duration_since_fail - should panic ... ok [INFO] [stdout] test tests::test_resume_blocks_until_unresumable_exits ... FAILED [INFO] [stdout] test tests::test_unpausable_wont_run_while_paused ... ok [INFO] [stdout] test tests::it_works ... FAILED [INFO] [stdout] test tests::test_pause_blocks_until_unpausable_exits ... ok [INFO] [stdout] test pausable_instant::tests::test_elapsed ... ok [INFO] [stdout] test tests::test_start_paused ... ok [INFO] [stdout] test tests::test_multiple_pauses ... ok [INFO] [stdout] test tests::test_time_max_when_paused ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::test_resume_blocks_until_unresumable_exits stdout ---- [INFO] [stdout] thread 'tests::test_resume_blocks_until_unresumable_exits' panicked at 'assertion failed: `(left == right)` [INFO] [stdout] left: `1`, [INFO] [stdout] right: `0`', src/lib.rs:1072:9 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e31507a020 - std::backtrace_rs::backtrace::libunwind::trace::h552106febb15e17e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 1: 0x55e31507a020 - std::backtrace_rs::backtrace::trace_unsynchronized::hf7774f161cb6a040 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e31507a020 - std::sys_common::backtrace::_print_fmt::h0c305f5052d81630 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:66:5 [INFO] [stdout] 3: 0x55e31507a020 - ::fmt::h64b3e0a8deb0a611 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:45:22 [INFO] [stdout] 4: 0x55e31509cd8c - core::fmt::write::h8f671d0f7992fdc6 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/fmt/mod.rs:1198:17 [INFO] [stdout] 5: 0x55e315076945 - std::io::Write::write_fmt::hd208f914b48a2290 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/io/mod.rs:1672:15 [INFO] [stdout] 6: 0x55e31507ba81 - std::sys_common::backtrace::_print::h9fa97aca0ed3f804 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:48:5 [INFO] [stdout] 7: 0x55e31507ba81 - std::sys_common::backtrace::print::haab86c3a36d9a53e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:35:9 [INFO] [stdout] 8: 0x55e31507ba81 - std::panicking::default_hook::{{closure}}::h97ac82ba40b3820b [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:295:22 [INFO] [stdout] 9: 0x55e31507b6d6 - std::panicking::default_hook::h3a2e40383cd02365 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:311:9 [INFO] [stdout] 10: 0x55e31507c123 - std::panicking::rust_panic_with_hook::h91242c0d4adecfe2 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:698:17 [INFO] [stdout] 11: 0x55e31507c017 - std::panicking::begin_panic_handler::{{closure}}::h627cf56a9a0a9517 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:588:13 [INFO] [stdout] 12: 0x55e31507a4f4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf13451d299824903 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:138:18 [INFO] [stdout] 13: 0x55e31507bd42 - rust_begin_unwind [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:584:5 [INFO] [stdout] 14: 0x55e314ff6d33 - core::panicking::panic_fmt::ha6799a5d9621aa13 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panicking.rs:142:14 [INFO] [stdout] 15: 0x55e31509b8b8 - core::panicking::assert_failed_inner::h06a9ad83c1bddedb [INFO] [stdout] 16: 0x55e315006c1a - core::panicking::assert_failed::h41e1f2c69a44532a [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panicking.rs:181:5 [INFO] [stdout] 17: 0x55e31501470f - pausable_clock::tests::test_resume_blocks_until_unresumable_exits::hc7ea5dd5875b7622 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:1072:9 [INFO] [stdout] 18: 0x55e31501409a - pausable_clock::tests::test_resume_blocks_until_unresumable_exits::{{closure}}::hf009157f225bb7d0 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:1030:5 [INFO] [stdout] 19: 0x55e315018b7e - core::ops::function::FnOnce::call_once::h75655a930bbff695 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 20: 0x55e315055253 - core::ops::function::FnOnce::call_once::h0f9e5c16f2fad3fe [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 21: 0x55e315055253 - test::__rust_begin_short_backtrace::h6b395b12fb47e0c7 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:572:5 [INFO] [stdout] 22: 0x55e315053fec - as core::ops::function::FnOnce>::call_once::h116a04ad1adfbba8 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 23: 0x55e315053fec - as core::ops::function::FnOnce<()>>::call_once::h237703400c3f95d2 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 24: 0x55e315053fec - std::panicking::try::do_call::hb6f7087c5161b13a [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:492:40 [INFO] [stdout] 25: 0x55e315053fec - std::panicking::try::h40eb70910e5ec9fc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:456:19 [INFO] [stdout] 26: 0x55e315053fec - std::panic::catch_unwind::h9011aaff4baefe1e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panic.rs:137:14 [INFO] [stdout] 27: 0x55e315053fec - test::run_test_in_process::haab9d5dbf92a97fd [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:595:18 [INFO] [stdout] 28: 0x55e315053fec - test::run_test::run_test_inner::{{closure}}::h431ff305408eb101 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:489:39 [INFO] [stdout] 29: 0x55e31501fc2c - test::run_test::run_test_inner::{{closure}}::h1761b84f3aff0780 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:516:37 [INFO] [stdout] 30: 0x55e31501fc2c - std::sys_common::backtrace::__rust_begin_short_backtrace::heac1883bddbbfcd9 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:122:18 [INFO] [stdout] 31: 0x55e315025c3f - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h509e89854d46123e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/thread/mod.rs:505:17 [INFO] [stdout] 32: 0x55e315025c3f - as core::ops::function::FnOnce<()>>::call_once::h1b05c47dc35673b3 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 33: 0x55e315025c3f - std::panicking::try::do_call::hd58695587ce4c228 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:492:40 [INFO] [stdout] 34: 0x55e315025c3f - std::panicking::try::hc017871f7aa94d61 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:456:19 [INFO] [stdout] 35: 0x55e315025c3f - std::panic::catch_unwind::h38b4473a1f81bb80 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panic.rs:137:14 [INFO] [stdout] 36: 0x55e315025c3f - std::thread::Builder::spawn_unchecked_::{{closure}}::h361d7687a745025a [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/thread/mod.rs:504:30 [INFO] [stdout] 37: 0x55e315025c3f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h647e812f95f891a3 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 38: 0x55e315080693 - as core::ops::function::FnOnce>::call_once::hd2e3a9e0ae7d8026 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 39: 0x55e315080693 - as core::ops::function::FnOnce>::call_once::h4798a92ee79456cc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 40: 0x55e315080693 - std::sys::unix::thread::Thread::new::thread_start::h9be4f412ada0f0f9 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 41: 0x7f520f6f1609 - start_thread [INFO] [stdout] 42: 0x7f520f4c1133 - clone [INFO] [stdout] 43: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- tests::it_works stdout ---- [INFO] [stdout] thread '' panicked at 'assertion failed: elapsed_real_millis >= 1000', src/lib.rs:828:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e31507a020 - std::backtrace_rs::backtrace::libunwind::trace::h552106febb15e17e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 1: 0x55e31507a020 - std::backtrace_rs::backtrace::trace_unsynchronized::hf7774f161cb6a040 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e31507a020 - std::sys_common::backtrace::_print_fmt::h0c305f5052d81630 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:66:5 [INFO] [stdout] 3: 0x55e31507a020 - ::fmt::h64b3e0a8deb0a611 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:45:22 [INFO] [stdout] 4: 0x55e31509cd8c - core::fmt::write::h8f671d0f7992fdc6 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/fmt/mod.rs:1198:17 [INFO] [stdout] 5: 0x55e315076945 - std::io::Write::write_fmt::hd208f914b48a2290 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/io/mod.rs:1672:15 [INFO] [stdout] 6: 0x55e31507ba81 - std::sys_common::backtrace::_print::h9fa97aca0ed3f804 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:48:5 [INFO] [stdout] 7: 0x55e31507ba81 - std::sys_common::backtrace::print::haab86c3a36d9a53e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:35:9 [INFO] [stdout] 8: 0x55e31507ba81 - std::panicking::default_hook::{{closure}}::h97ac82ba40b3820b [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:295:22 [INFO] [stdout] 9: 0x55e31507b6d6 - std::panicking::default_hook::h3a2e40383cd02365 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:311:9 [INFO] [stdout] 10: 0x55e31507c123 - std::panicking::rust_panic_with_hook::h91242c0d4adecfe2 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:698:17 [INFO] [stdout] 11: 0x55e31507bfd9 - std::panicking::begin_panic_handler::{{closure}}::h627cf56a9a0a9517 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:586:13 [INFO] [stdout] 12: 0x55e31507a4f4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf13451d299824903 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:138:18 [INFO] [stdout] 13: 0x55e31507bd42 - rust_begin_unwind [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:584:5 [INFO] [stdout] 14: 0x55e314ff6d33 - core::panicking::panic_fmt::ha6799a5d9621aa13 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panicking.rs:142:14 [INFO] [stdout] 15: 0x55e314ff6bfd - core::panicking::panic::h95f2f565de971b85 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panicking.rs:48:5 [INFO] [stdout] 16: 0x55e315012268 - pausable_clock::tests::it_works::{{closure}}::h1e884b8b73e65e33 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:828:13 [INFO] [stdout] 17: 0x55e31500618e - std::sys_common::backtrace::__rust_begin_short_backtrace::hfa4a60e860a0eadb [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:122:18 [INFO] [stdout] 18: 0x55e315000a4a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h97419b29eed98ccc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/thread/mod.rs:505:17 [INFO] [stdout] 19: 0x55e3150150ce - as core::ops::function::FnOnce<()>>::call_once::h191df18f49b32f3d [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 20: 0x55e315001e13 - std::panicking::try::do_call::he5e4d8fa099d008e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:492:40 [INFO] [stdout] 21: 0x55e3150024db - __rust_try [INFO] [stdout] 22: 0x55e3150018ca - std::panicking::try::he917cd36274899c1 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:456:19 [INFO] [stdout] 23: 0x55e31500666e - std::panic::catch_unwind::h001eb1aab231d1c5 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panic.rs:137:14 [INFO] [stdout] 24: 0x55e315000825 - std::thread::Builder::spawn_unchecked_::{{closure}}::hfcd930881a916fcc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/thread/mod.rs:504:30 [INFO] [stdout] 25: 0x55e31501881e - core::ops::function::FnOnce::call_once{{vtable.shim}}::h53b56f74a5b798ca [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 26: 0x55e315080693 - as core::ops::function::FnOnce>::call_once::hd2e3a9e0ae7d8026 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 27: 0x55e315080693 - as core::ops::function::FnOnce>::call_once::h4798a92ee79456cc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 28: 0x55e315080693 - std::sys::unix::thread::Thread::new::thread_start::h9be4f412ada0f0f9 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 29: 0x7f520f6f1609 - start_thread [INFO] [stdout] 30: 0x7f520f4c1133 - clone [INFO] [stdout] 31: 0x0 - [INFO] [stdout] thread 'tests::it_works' panicked at 'Must be an assert fail in spawned thread: Any { .. }', src/lib.rs:840:18 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e31507a020 - std::backtrace_rs::backtrace::libunwind::trace::h552106febb15e17e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 1: 0x55e31507a020 - std::backtrace_rs::backtrace::trace_unsynchronized::hf7774f161cb6a040 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e31507a020 - std::sys_common::backtrace::_print_fmt::h0c305f5052d81630 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:66:5 [INFO] [stdout] 3: 0x55e31507a020 - ::fmt::h64b3e0a8deb0a611 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:45:22 [INFO] [stdout] 4: 0x55e31509cd8c - core::fmt::write::h8f671d0f7992fdc6 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/fmt/mod.rs:1198:17 [INFO] [stdout] 5: 0x55e315076945 - std::io::Write::write_fmt::hd208f914b48a2290 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/io/mod.rs:1672:15 [INFO] [stdout] 6: 0x55e31507ba81 - std::sys_common::backtrace::_print::h9fa97aca0ed3f804 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:48:5 [INFO] [stdout] 7: 0x55e31507ba81 - std::sys_common::backtrace::print::haab86c3a36d9a53e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:35:9 [INFO] [stdout] 8: 0x55e31507ba81 - std::panicking::default_hook::{{closure}}::h97ac82ba40b3820b [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:295:22 [INFO] [stdout] 9: 0x55e31507b6d6 - std::panicking::default_hook::h3a2e40383cd02365 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:311:9 [INFO] [stdout] 10: 0x55e31507c123 - std::panicking::rust_panic_with_hook::h91242c0d4adecfe2 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:698:17 [INFO] [stdout] 11: 0x55e31507c017 - std::panicking::begin_panic_handler::{{closure}}::h627cf56a9a0a9517 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:588:13 [INFO] [stdout] 12: 0x55e31507a4f4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf13451d299824903 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:138:18 [INFO] [stdout] 13: 0x55e31507bd42 - rust_begin_unwind [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:584:5 [INFO] [stdout] 14: 0x55e314ff6d33 - core::panicking::panic_fmt::ha6799a5d9621aa13 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panicking.rs:142:14 [INFO] [stdout] 15: 0x55e314ff6e23 - core::result::unwrap_failed::h1269578c25bb65c7 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/result.rs:1805:5 [INFO] [stdout] 16: 0x55e315003eda - core::result::Result::expect::hc733edf3bf012f49 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/result.rs:1055:23 [INFO] [stdout] 17: 0x55e315011f62 - pausable_clock::tests::it_works::hdb62177fd910c8c0 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:840:9 [INFO] [stdout] 18: 0x55e315011bfa - pausable_clock::tests::it_works::{{closure}}::hf72a0b161b2f503e [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:805:5 [INFO] [stdout] 19: 0x55e315018abe - core::ops::function::FnOnce::call_once::h45761983516781b5 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 20: 0x55e315055253 - core::ops::function::FnOnce::call_once::h0f9e5c16f2fad3fe [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 21: 0x55e315055253 - test::__rust_begin_short_backtrace::h6b395b12fb47e0c7 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:572:5 [INFO] [stdout] 22: 0x55e315053fec - as core::ops::function::FnOnce>::call_once::h116a04ad1adfbba8 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 23: 0x55e315053fec - as core::ops::function::FnOnce<()>>::call_once::h237703400c3f95d2 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 24: 0x55e315053fec - std::panicking::try::do_call::hb6f7087c5161b13a [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:492:40 [INFO] [stdout] 25: 0x55e315053fec - std::panicking::try::h40eb70910e5ec9fc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:456:19 [INFO] [stdout] 26: 0x55e315053fec - std::panic::catch_unwind::h9011aaff4baefe1e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panic.rs:137:14 [INFO] [stdout] 27: 0x55e315053fec - test::run_test_in_process::haab9d5dbf92a97fd [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:595:18 [INFO] [stdout] 28: 0x55e315053fec - test::run_test::run_test_inner::{{closure}}::h431ff305408eb101 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:489:39 [INFO] [stdout] 29: 0x55e31501fc2c - test::run_test::run_test_inner::{{closure}}::h1761b84f3aff0780 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/test/src/lib.rs:516:37 [INFO] [stdout] 30: 0x55e31501fc2c - std::sys_common::backtrace::__rust_begin_short_backtrace::heac1883bddbbfcd9 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys_common/backtrace.rs:122:18 [INFO] [stdout] 31: 0x55e315025c3f - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h509e89854d46123e [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/thread/mod.rs:505:17 [INFO] [stdout] 32: 0x55e315025c3f - as core::ops::function::FnOnce<()>>::call_once::h1b05c47dc35673b3 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 33: 0x55e315025c3f - std::panicking::try::do_call::hd58695587ce4c228 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:492:40 [INFO] [stdout] 34: 0x55e315025c3f - std::panicking::try::hc017871f7aa94d61 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panicking.rs:456:19 [INFO] [stdout] 35: 0x55e315025c3f - std::panic::catch_unwind::h38b4473a1f81bb80 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/panic.rs:137:14 [INFO] [stdout] 36: 0x55e315025c3f - std::thread::Builder::spawn_unchecked_::{{closure}}::h361d7687a745025a [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/thread/mod.rs:504:30 [INFO] [stdout] 37: 0x55e315025c3f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h647e812f95f891a3 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/core/src/ops/function.rs:248:5 [INFO] [stdout] 38: 0x55e315080693 - as core::ops::function::FnOnce>::call_once::hd2e3a9e0ae7d8026 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 39: 0x55e315080693 - as core::ops::function::FnOnce>::call_once::h4798a92ee79456cc [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/alloc/src/boxed.rs:1934:9 [INFO] [stdout] 40: 0x55e315080693 - std::sys::unix::thread::Thread::new::thread_start::h9be4f412ada0f0f9 [INFO] [stdout] at /rustc/5bc97a20e5cc382ae6d19f727a9e73eeb2734548/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 41: 0x7f520f6f1609 - start_thread [INFO] [stdout] 42: 0x7f520f4c1133 - clone [INFO] [stdout] 43: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::it_works [INFO] [stdout] tests::test_resume_blocks_until_unresumable_exits [INFO] [stdout] [INFO] [stdout] test result: FAILED. 12 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.07s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass '--lib' [INFO] running `Command { std: "docker" "inspect" "adc11781f47e11216dcaed55152c3687cd47568a5b6bee5829f5a5b4e3bb2c1e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "adc11781f47e11216dcaed55152c3687cd47568a5b6bee5829f5a5b4e3bb2c1e", kill_on_drop: false }` [INFO] [stdout] adc11781f47e11216dcaed55152c3687cd47568a5b6bee5829f5a5b4e3bb2c1e