[INFO] fetching crate pausable_clock 1.0.1... [INFO] testing pausable_clock-1.0.1 against try#a40ec913238f4f188d97c41aa605cb486451acf2+target=i686-unknown-linux-musl for musl_1_2_upgrade_i686_unknown_linux_musl [INFO] extracting crate pausable_clock 1.0.1 into /workspace/builds/worker-6-tc2/source [INFO] validating manifest of crates.io crate pausable_clock 1.0.1 on toolchain a40ec913238f4f188d97c41aa605cb486451acf2 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "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-6-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" __CARGO_TEST_CHANNEL_OVERRIDE_DO_NOT_USE_THIS="nightly" "/workspace/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 5f53a27197dcd1bdc5549b98a711af8075b0333031ab749c0bb837cb14aedd93 [INFO] running `Command { std: "docker" "start" "-a" "5f53a27197dcd1bdc5549b98a711af8075b0333031ab749c0bb837cb14aedd93", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "5f53a27197dcd1bdc5549b98a711af8075b0333031ab749c0bb837cb14aedd93", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5f53a27197dcd1bdc5549b98a711af8075b0333031ab749c0bb837cb14aedd93", kill_on_drop: false }` [INFO] [stdout] 5f53a27197dcd1bdc5549b98a711af8075b0333031ab749c0bb837cb14aedd93 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "build" "--frozen" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 59f81cae1f893adf54bfaafca92d06cf0705c727577b0a861fb2e3966973e522 [INFO] running `Command { std: "docker" "start" "-a" "59f81cae1f893adf54bfaafca92d06cf0705c727577b0a861fb2e3966973e522", 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.22s [INFO] running `Command { std: "docker" "inspect" "59f81cae1f893adf54bfaafca92d06cf0705c727577b0a861fb2e3966973e522", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "59f81cae1f893adf54bfaafca92d06cf0705c727577b0a861fb2e3966973e522", kill_on_drop: false }` [INFO] [stdout] 59f81cae1f893adf54bfaafca92d06cf0705c727577b0a861fb2e3966973e522 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "test" "--frozen" "--no-run" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] c1494b1fde9901531f1b5bb0b4bab88e939ee54b71b09e5d29de0ffde13abdf6 [INFO] running `Command { std: "docker" "start" "-a" "c1494b1fde9901531f1b5bb0b4bab88e939ee54b71b09e5d29de0ffde13abdf6", kill_on_drop: false }` [INFO] [stderr] Compiling either v1.8.1 [INFO] [stderr] Compiling plotters-backend v0.3.4 [INFO] [stderr] Compiling unicode-width v0.1.10 [INFO] [stderr] Compiling cast v0.3.0 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling bstr v0.2.17 [INFO] [stderr] Compiling serde_json v1.0.91 [INFO] [stderr] Compiling half v1.8.2 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling regex v1.7.1 [INFO] [stderr] Compiling oorandom v11.1.3 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling rayon v1.6.1 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling plotters-svg v0.3.3 [INFO] [stderr] Compiling serde_cbor v0.11.2 [INFO] [stderr] Compiling plotters v0.3.4 [INFO] [stderr] Compiling csv v1.1.6 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling criterion-plot v0.4.5 [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 8.31s [INFO] running `Command { std: "docker" "inspect" "c1494b1fde9901531f1b5bb0b4bab88e939ee54b71b09e5d29de0ffde13abdf6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c1494b1fde9901531f1b5bb0b4bab88e939ee54b71b09e5d29de0ffde13abdf6", kill_on_drop: false }` [INFO] [stdout] c1494b1fde9901531f1b5bb0b4bab88e939ee54b71b09e5d29de0ffde13abdf6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+a40ec913238f4f188d97c41aa605cb486451acf2" "test" "--frozen" "--target" "i686-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] c4694150ab86a95208e1715c31e2ccdd3ed5d0d647071e8653ee34ab49f2383d [INFO] running `Command { std: "docker" "start" "-a" "c4694150ab86a95208e1715c31e2ccdd3ed5d0d647071e8653ee34ab49f2383d", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.09s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/i686-unknown-linux-musl/debug/deps/pausable_clock-d342a89f7322d0dc) [INFO] [stdout] [INFO] [stdout] running 14 tests [INFO] [stdout] test pausable_instant::tests::test_checked_add ... ok [INFO] [stdout] test pausable_instant::tests::test_checked_sub ... ok [INFO] [stdout] test pausable_instant::tests::test_duration_since ... ok [INFO] [stdout] test pause_state::tests::test_accurate_millis ... ok [INFO] [stdout] test pausable_instant::tests::test_duration_since_fail - should panic ... ok [INFO] [stdout] test pausable_instant::tests::test_elapsed_fail - should panic ... ok [INFO] [stdout] test tests::test_unpausable_wont_run_while_paused ... ok [INFO] [stdout] test tests::test_pause_blocks_until_unpausable_exits ... ok [INFO] [stdout] test tests::test_resume_blocks_until_unresumable_exits ... ok [INFO] [stdout] test tests::it_works ... FAILED [INFO] [stdout] test tests::test_start_paused ... ok [INFO] [stdout] test pausable_instant::tests::test_elapsed ... FAILED [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::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: 0x80ccca1 - std::backtrace_rs::backtrace::libunwind::trace::h104fa452555c99d8 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x80ccca1 - std::backtrace_rs::backtrace::trace_unsynchronized::h0a8252d88f024747 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x80ccca1 - std::sys_common::backtrace::_print_fmt::h5cd63a03c21f7741 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x80ccca1 - ::fmt::hbd8d931cffed74c6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x810a9bc - core::fmt::write::hf9516172e71ddf40 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/fmt/mod.rs:1213:17 [INFO] [stdout] 5: 0x80ca27b - std::io::Write::write_fmt::hdf04330b9e16e0c0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x80ccaa6 - std::sys_common::backtrace::_print::hb81277fe50a8d899 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x80ccaa6 - std::sys_common::backtrace::print::ha87c182f636b8daf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x80ce685 - std::panicking::default_hook::{{closure}}::he705de5c2afaeb86 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x80ce385 - std::panicking::default_hook::h18d980bdf65e921f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x80ac28c - core::ops::function::Fn::call::h352171db07b38cc9 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:79:5 [INFO] [stdout] 11: 0x80a26b9 - as core::ops::function::Fn>::call::h087b52e51e83fc8e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 12: 0x80a26b9 - test::test_main::{{closure}}::hb854e86f34352ae6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:135:21 [INFO] [stdout] 13: 0x80cedc1 - as core::ops::function::Fn>::call::h843cec44e0c3e0b1 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 14: 0x80cedc1 - std::panicking::rust_panic_with_hook::he61419fbe644c5af [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:692:13 [INFO] [stdout] 15: 0x80ceb31 - std::panicking::begin_panic_handler::{{closure}}::h2a512cdc056f1e5c [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:577:13 [INFO] [stdout] 16: 0x80cd11a - std::sys_common::backtrace::__rust_end_short_backtrace::h00cf281ca60df1fa [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 17: 0x80ce853 - rust_begin_unwind [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:575:5 [INFO] [stdout] 18: 0x804c600 - core::panicking::panic_fmt::h5fe5ec922118750e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panicking.rs:64:14 [INFO] [stdout] 19: 0x804c6b3 - core::panicking::panic::hbcc1d3adcd44acc0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panicking.rs:114:5 [INFO] [stdout] 20: 0x805f02d - pausable_clock::tests::it_works::{{closure}}::h9148a0c91a0239e8 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:828:13 [INFO] [stdout] 21: 0x805a74e - std::sys_common::backtrace::__rust_begin_short_backtrace::h2bd2a215470ea582 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 22: 0x805435c - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h1f8bf2e34c1fdd38 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/thread/mod.rs:558:17 [INFO] [stdout] 23: 0x8070ade - as core::ops::function::FnOnce<()>>::call_once::hfdfcaa0b0edebfa7 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 24: 0x8073601 - std::panicking::try::do_call::h085ede90e10027af [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:483:40 [INFO] [stdout] 25: 0x8073e5c - __rust_try [INFO] [stdout] 26: 0x8073217 - std::panicking::try::h19a370c0d1cc879d [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:447:19 [INFO] [stdout] 27: 0x805866d - std::panic::catch_unwind::ha08f8d0f26c1fd2b [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panic.rs:140:14 [INFO] [stdout] 28: 0x8052d54 - std::thread::Builder::spawn_unchecked_::{{closure}}::h12c3c984bcc18c86 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 29: 0x806be4e - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7111736016898575 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 30: 0x80d2ced - as core::ops::function::FnOnce>::call_once::hbcf52fd66176976e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 31: 0x80d2ced - as core::ops::function::FnOnce>::call_once::h5505c14554e15331 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 32: 0x80d2ced - std::sys::unix::thread::Thread::new::thread_start::h6464f27a12864ff1 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys/unix/thread.rs:108:17 [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: 0x80ccca1 - std::backtrace_rs::backtrace::libunwind::trace::h104fa452555c99d8 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x80ccca1 - std::backtrace_rs::backtrace::trace_unsynchronized::h0a8252d88f024747 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x80ccca1 - std::sys_common::backtrace::_print_fmt::h5cd63a03c21f7741 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x80ccca1 - ::fmt::hbd8d931cffed74c6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x810a9bc - core::fmt::write::hf9516172e71ddf40 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/fmt/mod.rs:1213:17 [INFO] [stdout] 5: 0x80ca27b - std::io::Write::write_fmt::hdf04330b9e16e0c0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x80ccaa6 - std::sys_common::backtrace::_print::hb81277fe50a8d899 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x80ccaa6 - std::sys_common::backtrace::print::ha87c182f636b8daf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x80ce685 - std::panicking::default_hook::{{closure}}::he705de5c2afaeb86 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x80ce385 - std::panicking::default_hook::h18d980bdf65e921f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x80ac28c - core::ops::function::Fn::call::h352171db07b38cc9 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:79:5 [INFO] [stdout] 11: 0x80a26b9 - as core::ops::function::Fn>::call::h087b52e51e83fc8e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 12: 0x80a26b9 - test::test_main::{{closure}}::hb854e86f34352ae6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:135:21 [INFO] [stdout] 13: 0x80cedc1 - as core::ops::function::Fn>::call::h843cec44e0c3e0b1 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 14: 0x80cedc1 - std::panicking::rust_panic_with_hook::he61419fbe644c5af [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:692:13 [INFO] [stdout] 15: 0x80ceb7c - std::panicking::begin_panic_handler::{{closure}}::h2a512cdc056f1e5c [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:579:13 [INFO] [stdout] 16: 0x80cd11a - std::sys_common::backtrace::__rust_end_short_backtrace::h00cf281ca60df1fa [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 17: 0x80ce853 - rust_begin_unwind [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:575:5 [INFO] [stdout] 18: 0x804c600 - core::panicking::panic_fmt::h5fe5ec922118750e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panicking.rs:64:14 [INFO] [stdout] 19: 0x804c9fd - core::result::unwrap_failed::he01247b265153967 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/result.rs:1790:5 [INFO] [stdout] 20: 0x805ad9f - core::result::Result::expect::h76cb2d2b2629a491 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/result.rs:1069:23 [INFO] [stdout] 21: 0x805eba8 - pausable_clock::tests::it_works::hbe18742bf0beb035 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:840:9 [INFO] [stdout] 22: 0x805e747 - pausable_clock::tests::it_works::{{closure}}::h71cbe60f2cef228f [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:805:19 [INFO] [stdout] 23: 0x806c38d - core::ops::function::FnOnce::call_once::h98c110ffc2e11bb0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x80a710e - core::ops::function::FnOnce::call_once::h91d0dfda8bb9938e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x80a710e - test::__rust_begin_short_backtrace::h1b678021c2c7c382 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:656:18 [INFO] [stdout] 26: 0x807ac40 - test::run_test::{{closure}}::h0608bfc5582d1472 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:647:30 [INFO] [stdout] 27: 0x807ac40 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0171ab92c79d505a [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 28: 0x80a6287 - as core::ops::function::FnOnce>::call_once::h44bb5e1b0b78658f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 29: 0x80a6287 - as core::ops::function::FnOnce<()>>::call_once::hf14b9bc9243bc6b0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 30: 0x80a6287 - std::panicking::try::do_call::h2614ccc7313d3f7f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:483:40 [INFO] [stdout] 31: 0x80a6287 - std::panicking::try::h9ae839d4ad9722f8 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:447:19 [INFO] [stdout] 32: 0x80a6287 - std::panic::catch_unwind::hc1cb34e922e62e51 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panic.rs:140:14 [INFO] [stdout] 33: 0x80a6287 - test::run_test_in_process::he32539bd91b43aae [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:679:27 [INFO] [stdout] 34: 0x80a6287 - test::run_test::run_test_inner::{{closure}}::h251e49a2e77c04ae [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:573:39 [INFO] [stdout] 35: 0x80755ca - test::run_test::run_test_inner::{{closure}}::h8efa48cfafca95a9 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:600:37 [INFO] [stdout] 36: 0x80755ca - std::sys_common::backtrace::__rust_begin_short_backtrace::hd8d1cab504dcff1d [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 37: 0x807af3b - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h6b7049d1c34b0ebf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/thread/mod.rs:558:17 [INFO] [stdout] 38: 0x807af3b - as core::ops::function::FnOnce<()>>::call_once::h851a140381089cab [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 39: 0x807af3b - std::panicking::try::do_call::hd62be527b2c88583 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:483:40 [INFO] [stdout] 40: 0x807af3b - std::panicking::try::h224f4be6bf8f39b0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:447:19 [INFO] [stdout] 41: 0x807af3b - std::panic::catch_unwind::he51e6d58f5cc977e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panic.rs:140:14 [INFO] [stdout] 42: 0x807af3b - std::thread::Builder::spawn_unchecked_::{{closure}}::hdeb5cc8debe6cfbf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 43: 0x807af3b - core::ops::function::FnOnce::call_once{{vtable.shim}}::h37aeaa4cf4078e37 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 44: 0x80d2ced - as core::ops::function::FnOnce>::call_once::hbcf52fd66176976e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 45: 0x80d2ced - as core::ops::function::FnOnce>::call_once::h5505c14554e15331 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 46: 0x80d2ced - std::sys::unix::thread::Thread::new::thread_start::h6464f27a12864ff1 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] [INFO] [stdout] ---- pausable_instant::tests::test_elapsed stdout ---- [INFO] [stdout] thread 'pausable_instant::tests::test_elapsed' panicked at 'assertion failed: (expected_elapsed.as_secs_f64() - 1.0).abs() < 0.005', src/pausable_instant.rs:228:9 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x80ccca1 - std::backtrace_rs::backtrace::libunwind::trace::h104fa452555c99d8 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x80ccca1 - std::backtrace_rs::backtrace::trace_unsynchronized::h0a8252d88f024747 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x80ccca1 - std::sys_common::backtrace::_print_fmt::h5cd63a03c21f7741 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x80ccca1 - ::fmt::hbd8d931cffed74c6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x810a9bc - core::fmt::write::hf9516172e71ddf40 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/fmt/mod.rs:1213:17 [INFO] [stdout] 5: 0x80ca27b - std::io::Write::write_fmt::hdf04330b9e16e0c0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x80ccaa6 - std::sys_common::backtrace::_print::hb81277fe50a8d899 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x80ccaa6 - std::sys_common::backtrace::print::ha87c182f636b8daf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x80ce685 - std::panicking::default_hook::{{closure}}::he705de5c2afaeb86 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x80ce385 - std::panicking::default_hook::h18d980bdf65e921f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x80ac28c - core::ops::function::Fn::call::h352171db07b38cc9 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:79:5 [INFO] [stdout] 11: 0x80a26b9 - as core::ops::function::Fn>::call::h087b52e51e83fc8e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 12: 0x80a26b9 - test::test_main::{{closure}}::hb854e86f34352ae6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:135:21 [INFO] [stdout] 13: 0x80cedc1 - as core::ops::function::Fn>::call::h843cec44e0c3e0b1 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 14: 0x80cedc1 - std::panicking::rust_panic_with_hook::he61419fbe644c5af [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:692:13 [INFO] [stdout] 15: 0x80ceb31 - std::panicking::begin_panic_handler::{{closure}}::h2a512cdc056f1e5c [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:577:13 [INFO] [stdout] 16: 0x80cd11a - std::sys_common::backtrace::__rust_end_short_backtrace::h00cf281ca60df1fa [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 17: 0x80ce853 - rust_begin_unwind [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:575:5 [INFO] [stdout] 18: 0x804c600 - core::panicking::panic_fmt::h5fe5ec922118750e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panicking.rs:64:14 [INFO] [stdout] 19: 0x804c6b3 - core::panicking::panic::hbcc1d3adcd44acc0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panicking.rs:114:5 [INFO] [stdout] 20: 0x8059768 - pausable_clock::pausable_instant::tests::test_elapsed::h6e9a60c4d3362b90 [INFO] [stdout] at /opt/rustwide/workdir/src/pausable_instant.rs:228:9 [INFO] [stdout] 21: 0x80594f7 - pausable_clock::pausable_instant::tests::test_elapsed::{{closure}}::hef7e290d1d280d36 [INFO] [stdout] at /opt/rustwide/workdir/src/pausable_instant.rs:213:23 [INFO] [stdout] 22: 0x806bf7d - core::ops::function::FnOnce::call_once::h135801746b2585f6 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x80a710e - core::ops::function::FnOnce::call_once::h91d0dfda8bb9938e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x80a710e - test::__rust_begin_short_backtrace::h1b678021c2c7c382 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:656:18 [INFO] [stdout] 25: 0x807ac40 - test::run_test::{{closure}}::h0608bfc5582d1472 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:647:30 [INFO] [stdout] 26: 0x807ac40 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0171ab92c79d505a [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 27: 0x80a6287 - as core::ops::function::FnOnce>::call_once::h44bb5e1b0b78658f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 28: 0x80a6287 - as core::ops::function::FnOnce<()>>::call_once::hf14b9bc9243bc6b0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 29: 0x80a6287 - std::panicking::try::do_call::h2614ccc7313d3f7f [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:483:40 [INFO] [stdout] 30: 0x80a6287 - std::panicking::try::h9ae839d4ad9722f8 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:447:19 [INFO] [stdout] 31: 0x80a6287 - std::panic::catch_unwind::hc1cb34e922e62e51 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panic.rs:140:14 [INFO] [stdout] 32: 0x80a6287 - test::run_test_in_process::he32539bd91b43aae [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:679:27 [INFO] [stdout] 33: 0x80a6287 - test::run_test::run_test_inner::{{closure}}::h251e49a2e77c04ae [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:573:39 [INFO] [stdout] 34: 0x80755ca - test::run_test::run_test_inner::{{closure}}::h8efa48cfafca95a9 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/test/src/lib.rs:600:37 [INFO] [stdout] 35: 0x80755ca - std::sys_common::backtrace::__rust_begin_short_backtrace::hd8d1cab504dcff1d [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 36: 0x807af3b - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h6b7049d1c34b0ebf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/thread/mod.rs:558:17 [INFO] [stdout] 37: 0x807af3b - as core::ops::function::FnOnce<()>>::call_once::h851a140381089cab [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 38: 0x807af3b - std::panicking::try::do_call::hd62be527b2c88583 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:483:40 [INFO] [stdout] 39: 0x807af3b - std::panicking::try::h224f4be6bf8f39b0 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panicking.rs:447:19 [INFO] [stdout] 40: 0x807af3b - std::panic::catch_unwind::he51e6d58f5cc977e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/panic.rs:140:14 [INFO] [stdout] 41: 0x807af3b - std::thread::Builder::spawn_unchecked_::{{closure}}::hdeb5cc8debe6cfbf [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 42: 0x807af3b - core::ops::function::FnOnce::call_once{{vtable.shim}}::h37aeaa4cf4078e37 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 43: 0x80d2ced - as core::ops::function::FnOnce>::call_once::hbcf52fd66176976e [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 44: 0x80d2ced - as core::ops::function::FnOnce>::call_once::h5505c14554e15331 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 45: 0x80d2ced - std::sys::unix::thread::Thread::new::thread_start::h6464f27a12864ff1 [INFO] [stdout] at /rustc/a40ec913238f4f188d97c41aa605cb486451acf2/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] pausable_instant::tests::test_elapsed [INFO] [stdout] tests::it_works [INFO] [stdout] [INFO] [stdout] test result: FAILED. 12 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.70s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "c4694150ab86a95208e1715c31e2ccdd3ed5d0d647071e8653ee34ab49f2383d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c4694150ab86a95208e1715c31e2ccdd3ed5d0d647071e8653ee34ab49f2383d", kill_on_drop: false }` [INFO] [stdout] c4694150ab86a95208e1715c31e2ccdd3ed5d0d647071e8653ee34ab49f2383d