[INFO] fetching crate tokio-timer-plus 0.1.3... [INFO] testing tokio-timer-plus-0.1.3 against beta-2021-01-01 for beta-1.50-1 [INFO] extracting crate tokio-timer-plus 0.1.3 into /workspace/builds/worker-1/source [INFO] validating manifest of crates.io crate tokio-timer-plus 0.1.3 on toolchain beta-2021-01-01 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] started tweaking crates.io crate tokio-timer-plus 0.1.3 [INFO] finished tweaking crates.io crate tokio-timer-plus 0.1.3 [INFO] tweaked toml for crates.io crate tokio-timer-plus 0.1.3 written to /workspace/builds/worker-1/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 4fce47f03b0fd5b7bd507a3dc8fcf6a2df82e12c731b306857b0e9aa9180f060 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "4fce47f03b0fd5b7bd507a3dc8fcf6a2df82e12c731b306857b0e9aa9180f060", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "4fce47f03b0fd5b7bd507a3dc8fcf6a2df82e12c731b306857b0e9aa9180f060", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4fce47f03b0fd5b7bd507a3dc8fcf6a2df82e12c731b306857b0e9aa9180f060", kill_on_drop: false }` [INFO] [stdout] 4fce47f03b0fd5b7bd507a3dc8fcf6a2df82e12c731b306857b0e9aa9180f060 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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=warn" "-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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 496d77fc17e5d569055226786b63b14d8ce919b500a9cf1a0d66b4c3de257026 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "496d77fc17e5d569055226786b63b14d8ce919b500a9cf1a0d66b4c3de257026", kill_on_drop: false }` [INFO] [stderr] Compiling tokio-timer-plus v0.1.3 (/opt/rustwide/workdir) [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:421:27 [INFO] [stdout] | [INFO] [stdout] 421 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] note: the lint level is defined here [INFO] [stdout] --> src/lib.rs:88:9 [INFO] [stdout] | [INFO] [stdout] 88 | #![deny(warnings, missing_docs, missing_debug_implementations)] [INFO] [stdout] | ^^^^^^^^ [INFO] [stdout] = note: `#[warn(deprecated)]` implied by `#[warn(warnings)]` [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:436:27 [INFO] [stdout] | [INFO] [stdout] 436 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:442:27 [INFO] [stdout] | [INFO] [stdout] 442 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/mpmc.rs:77:22 [INFO] [stdout] | [INFO] [stdout] 77 | .compare_and_swap(pos, pos+1, Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/mpmc.rs:111:52 [INFO] [stdout] | [INFO] [stdout] 111 | let dequeue_pos = self.dequeue_pos.compare_and_swap(pos, pos+1, Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 5 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.65s [INFO] running `Command { std: "docker" "inspect" "496d77fc17e5d569055226786b63b14d8ce919b500a9cf1a0d66b4c3de257026", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "496d77fc17e5d569055226786b63b14d8ce919b500a9cf1a0d66b4c3de257026", kill_on_drop: false }` [INFO] [stdout] 496d77fc17e5d569055226786b63b14d8ce919b500a9cf1a0d66b4c3de257026 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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=warn" "-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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 34328e2ae7ed2c58fab68c3f3abb8defe8924001f2dba0c0facae9fa0d4d5a27 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "34328e2ae7ed2c58fab68c3f3abb8defe8924001f2dba0c0facae9fa0d4d5a27", kill_on_drop: false }` [INFO] [stderr] Compiling tokio-timer-plus v0.1.3 (/opt/rustwide/workdir) [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:421:27 [INFO] [stdout] | [INFO] [stdout] 421 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] note: the lint level is defined here [INFO] [stdout] --> src/lib.rs:88:9 [INFO] [stdout] | [INFO] [stdout] 88 | #![deny(warnings, missing_docs, missing_debug_implementations)] [INFO] [stdout] | ^^^^^^^^ [INFO] [stdout] = note: `#[warn(deprecated)]` implied by `#[warn(warnings)]` [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:436:27 [INFO] [stdout] | [INFO] [stdout] 436 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:442:27 [INFO] [stdout] | [INFO] [stdout] 442 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/mpmc.rs:77:22 [INFO] [stdout] | [INFO] [stdout] 77 | .compare_and_swap(pos, pos+1, Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/mpmc.rs:111:52 [INFO] [stdout] | [INFO] [stdout] 111 | let dequeue_pos = self.dequeue_pos.compare_and_swap(pos, pos+1, Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 5 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:421:27 [INFO] [stdout] | [INFO] [stdout] 421 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] note: the lint level is defined here [INFO] [stdout] --> src/lib.rs:88:9 [INFO] [stdout] | [INFO] [stdout] 88 | #![deny(warnings, missing_docs, missing_debug_implementations)] [INFO] [stdout] | ^^^^^^^^ [INFO] [stdout] = note: `#[warn(deprecated)]` implied by `#[warn(warnings)]` [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:436:27 [INFO] [stdout] | [INFO] [stdout] 436 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/timer.rs:442:27 [INFO] [stdout] | [INFO] [stdout] 442 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/mpmc.rs:77:22 [INFO] [stdout] | [INFO] [stdout] 77 | .compare_and_swap(pos, pos+1, Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/mpmc.rs:111:52 [INFO] [stdout] | [INFO] [stdout] 111 | let dequeue_pos = self.dequeue_pos.compare_and_swap(pos, pos+1, Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 5 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 3.17s [INFO] running `Command { std: "docker" "inspect" "34328e2ae7ed2c58fab68c3f3abb8defe8924001f2dba0c0facae9fa0d4d5a27", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "34328e2ae7ed2c58fab68c3f3abb8defe8924001f2dba0c0facae9fa0d4d5a27", kill_on_drop: false }` [INFO] [stdout] 34328e2ae7ed2c58fab68c3f3abb8defe8924001f2dba0c0facae9fa0d4d5a27 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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=warn" "-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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "test" "--frozen", kill_on_drop: false }` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] f7de8ec9c17bc7b74eb5af5cbd136f0a0b707cd8a882e1a163215f28ad199c7d [INFO] running `Command { std: "docker" "start" "-a" "f7de8ec9c17bc7b74eb5af5cbd136f0a0b707cd8a882e1a163215f28ad199c7d", kill_on_drop: false }` [INFO] [stdout] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] running 0 tests [INFO] [stderr] --> src/timer.rs:421:27 [INFO] [stdout] [INFO] [stderr] | [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stderr] 421 | write!(fmt, "{}", Error::description(self)) [INFO] [stdout] [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] note: the lint level is defined here [INFO] [stderr] --> src/lib.rs:88:9 [INFO] [stderr] | [INFO] [stderr] 88 | #![deny(warnings, missing_docs, missing_debug_implementations)] [INFO] [stderr] | ^^^^^^^^ [INFO] [stderr] = note: `#[warn(deprecated)]` implied by `#[warn(warnings)]` [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/timer.rs:436:27 [INFO] [stderr] | [INFO] [stderr] 436 | write!(fmt, "{}", Error::description(self)) [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/timer.rs:442:27 [INFO] [stderr] | [INFO] [stderr] 442 | write!(fmt, "{}", Error::description(self)) [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/mpmc.rs:77:22 [INFO] [stderr] | [INFO] [stderr] 77 | .compare_and_swap(pos, pos+1, Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/mpmc.rs:111:52 [INFO] [stderr] | [INFO] [stderr] 111 | let dequeue_pos = self.dequeue_pos.compare_and_swap(pos, pos+1, Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: 5 warnings emitted [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/timer.rs:421:27 [INFO] [stderr] | [INFO] [stderr] 421 | write!(fmt, "{}", Error::description(self)) [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] note: the lint level is defined here [INFO] [stderr] --> src/lib.rs:88:9 [INFO] [stderr] | [INFO] [stderr] 88 | #![deny(warnings, missing_docs, missing_debug_implementations)] [INFO] [stderr] | ^^^^^^^^ [INFO] [stderr] = note: `#[warn(deprecated)]` implied by `#[warn(warnings)]` [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/timer.rs:436:27 [INFO] [stderr] | [INFO] [stderr] 436 | write!(fmt, "{}", Error::description(self)) [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/timer.rs:442:27 [INFO] [stderr] | [INFO] [stderr] 442 | write!(fmt, "{}", Error::description(self)) [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/mpmc.rs:77:22 [INFO] [stderr] | [INFO] [stderr] 77 | .compare_and_swap(pos, pos+1, Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/mpmc.rs:111:52 [INFO] [stderr] | [INFO] [stderr] 111 | let dequeue_pos = self.dequeue_pos.compare_and_swap(pos, pos+1, Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: 5 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tokio_timer-5e552a9b0834fb45 [INFO] [stderr] Running /opt/rustwide/target/debug/deps/support-ed8e567cd44d6b55 [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/test_timer-4350359d8e933503 [INFO] [stdout] running 0 tests [INFO] [stdout] [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 15 tests [INFO] [stdout] test test_immediate_sleep ... ok [INFO] [stdout] test test_interval_at_past ... ok [INFO] [stdout] test test_timeout_with_future_errors_first ... ok [INFO] [stdout] test test_timeout_with_future_completes_first ... ok [INFO] [stdout] test test_timeout_stream_with_stream_completes_first ... ok [INFO] [stdout] test test_interval_once ... FAILED [INFO] [stdout] test test_timeout_with_timeout_completes_first ... ok [INFO] [stdout] test test_timeout_stream_with_timeout_completes_first ... ok [INFO] [stdout] test test_request_sleep_greater_than_max ... ok [INFO] [stdout] test test_setting_later_sleep_then_earlier_one ... ok [INFO] [stdout] test test_interval_at_once ... ok [INFO] [stdout] test test_interval_twice ... FAILED [INFO] [stdout] test test_interval_at_twice ... FAILED [INFO] [stdout] test test_timer_with_looping_wheel ... ok [INFO] [stderr] error: test failed, to rerun pass '--test test_timer' [INFO] [stdout] test test_delayed_sleep ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test_interval_once stdout ---- [INFO] [stdout] thread 'test_interval_once' panicked at 'expect=300ms; actual=273.647798ms', tests/support.rs:24:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55cf24a0de77 - std::backtrace_rs::backtrace::libunwind::trace::hcd6e187547b8deec [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x55cf24a0de77 - std::backtrace_rs::backtrace::trace_unsynchronized::hde10036eb783fbc7 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55cf24a0de77 - std::sys_common::backtrace::_print_fmt::h63987b8f86dfc82a [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x55cf24a0de77 - ::fmt::hc33f3ac4cc9c4196 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x55cf24a2dfdc - core::fmt::write::hfdcdeb08fb2b3afa [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/fmt/mod.rs:1078:17 [INFO] [stdout] 5: 0x55cf24a09f82 - std::io::Write::write_fmt::h28c53dc51251a293 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/io/mod.rs:1519:15 [INFO] [stdout] 6: 0x55cf24a10175 - std::sys_common::backtrace::_print::hf179508b01f66878 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x55cf24a10175 - std::sys_common::backtrace::print::ha97d11adb39266a6 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x55cf24a10175 - std::panicking::default_hook::{{closure}}::h8da9985a98bd1582 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x55cf24a0fd02 - std::panicking::default_hook::hbc0ee792993c2ef2 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x55cf24a10911 - std::panicking::rust_panic_with_hook::h9fb9cda33a10f084 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:591:17 [INFO] [stdout] 11: 0x55cf24a10457 - std::panicking::begin_panic_handler::{{closure}}::hf3e83396e085962e [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:497:13 [INFO] [stdout] 12: 0x55cf24a0e33c - std::sys_common::backtrace::__rust_end_short_backtrace::hac151ac4c1e3252d [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x55cf24a103b9 - rust_begin_unwind [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:493:5 [INFO] [stdout] 14: 0x55cf24a1036b - std::panicking::begin_panic_fmt::h01a0df196b065ae4 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:435:5 [INFO] [stdout] 15: 0x55cf24975aad - test_timer::support::Elapsed::assert_is_about::h7c29c53b89c14f91 [INFO] [stdout] at /opt/rustwide/workdir/tests/support.rs:24:13 [INFO] [stdout] 16: 0x55cf24960187 - test_timer::test_interval_once::h6ce0321980947b18 [INFO] [stdout] at /opt/rustwide/workdir/tests/test_timer.rs:252:5 [INFO] [stdout] 17: 0x55cf2496008a - test_timer::test_interval_once::{{closure}}::h45d0a482a9ba7c37 [INFO] [stdout] at /opt/rustwide/workdir/tests/test_timer.rs:243:1 [INFO] [stdout] 18: 0x55cf2497c96e - core::ops::function::FnOnce::call_once::h8cd0ee968741ce7c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x55cf249aef33 - core::ops::function::FnOnce::call_once::ha61d5393ae51ae22 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 20: 0x55cf249aef33 - test::__rust_begin_short_backtrace::h4a8691b5281c1f3b [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:516:5 [INFO] [stdout] 21: 0x55cf249ad826 - as core::ops::function::FnOnce>::call_once::hd25ba61494055ad3 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 22: 0x55cf249ad826 - as core::ops::function::FnOnce<()>>::call_once::h052fde7b846767ba [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:322:9 [INFO] [stdout] 23: 0x55cf249ad826 - std::panicking::try::do_call::h04571a1da6838ba1 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:379:40 [INFO] [stdout] 24: 0x55cf249ad826 - std::panicking::try::h758f090614125cbe [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:343:19 [INFO] [stdout] 25: 0x55cf249ad826 - std::panic::catch_unwind::h0c48513d5a021d3c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:396:14 [INFO] [stdout] 26: 0x55cf249ad826 - test::run_test_in_process::hee75babaaac3822f [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:538:18 [INFO] [stdout] 27: 0x55cf249ad826 - test::run_test::run_test_inner::{{closure}}::h2920dea75d7f0c1e [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:449:39 [INFO] [stdout] 28: 0x55cf24985b36 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha23dff608830db13 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 29: 0x55cf2498a8cd - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hacce322dead3a799 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/thread/mod.rs:474:17 [INFO] [stdout] 30: 0x55cf2498a8cd - as core::ops::function::FnOnce<()>>::call_once::h4f0bc9c26ead0a27 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:322:9 [INFO] [stdout] 31: 0x55cf2498a8cd - std::panicking::try::do_call::h1b9f282fb0c383b3 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:379:40 [INFO] [stdout] 32: 0x55cf2498a8cd - std::panicking::try::hed1ebed51b133ee4 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:343:19 [INFO] [stdout] 33: 0x55cf2498a8cd - std::panic::catch_unwind::h072a2b19fd39a474 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:396:14 [INFO] [stdout] 34: 0x55cf2498a8cd - std::thread::Builder::spawn_unchecked::{{closure}}::hb02c6b2d3ff18bc2 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/thread/mod.rs:473:30 [INFO] [stdout] 35: 0x55cf2498a8cd - core::ops::function::FnOnce::call_once{{vtable.shim}}::hb512c5d27b4adb83 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 36: 0x55cf24a1648a - as core::ops::function::FnOnce>::call_once::hdbb9ff71143eaad6 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 37: 0x55cf24a1648a - as core::ops::function::FnOnce>::call_once::h9dbde95cefb8368c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 38: 0x55cf24a1648a - std::sys::unix::thread::Thread::new::thread_start::hc76032c38affd972 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys/unix/thread.rs:71:17 [INFO] [stdout] 39: 0x7f8ba6ef5609 - start_thread [INFO] [stdout] 40: 0x7f8ba6cc7293 - clone [INFO] [stdout] 41: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- test_interval_twice stdout ---- [INFO] [stdout] thread 'test_interval_twice' panicked at 'expect=600ms; actual=552.061729ms', tests/support.rs:24:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55cf24a0de77 - std::backtrace_rs::backtrace::libunwind::trace::hcd6e187547b8deec [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x55cf24a0de77 - std::backtrace_rs::backtrace::trace_unsynchronized::hde10036eb783fbc7 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55cf24a0de77 - std::sys_common::backtrace::_print_fmt::h63987b8f86dfc82a [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x55cf24a0de77 - ::fmt::hc33f3ac4cc9c4196 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x55cf24a2dfdc - core::fmt::write::hfdcdeb08fb2b3afa [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/fmt/mod.rs:1078:17 [INFO] [stdout] 5: 0x55cf24a09f82 - std::io::Write::write_fmt::h28c53dc51251a293 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/io/mod.rs:1519:15 [INFO] [stdout] 6: 0x55cf24a10175 - std::sys_common::backtrace::_print::hf179508b01f66878 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x55cf24a10175 - std::sys_common::backtrace::print::ha97d11adb39266a6 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x55cf24a10175 - std::panicking::default_hook::{{closure}}::h8da9985a98bd1582 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x55cf24a0fd02 - std::panicking::default_hook::hbc0ee792993c2ef2 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x55cf24a10911 - std::panicking::rust_panic_with_hook::h9fb9cda33a10f084 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:591:17 [INFO] [stdout] 11: 0x55cf24a10457 - std::panicking::begin_panic_handler::{{closure}}::hf3e83396e085962e [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:497:13 [INFO] [stdout] 12: 0x55cf24a0e33c - std::sys_common::backtrace::__rust_end_short_backtrace::hac151ac4c1e3252d [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x55cf24a103b9 - rust_begin_unwind [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:493:5 [INFO] [stdout] 14: 0x55cf24a1036b - std::panicking::begin_panic_fmt::h01a0df196b065ae4 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:435:5 [INFO] [stdout] 15: 0x55cf24975aad - test_timer::support::Elapsed::assert_is_about::h7c29c53b89c14f91 [INFO] [stdout] at /opt/rustwide/workdir/tests/support.rs:24:13 [INFO] [stdout] 16: 0x55cf24960344 - test_timer::test_interval_twice::h00cc3365735a4298 [INFO] [stdout] at /opt/rustwide/workdir/tests/test_timer.rs:266:5 [INFO] [stdout] 17: 0x55cf2496020a - test_timer::test_interval_twice::{{closure}}::h2a7e28d688bc9755 [INFO] [stdout] at /opt/rustwide/workdir/tests/test_timer.rs:256:1 [INFO] [stdout] 18: 0x55cf2497c93e - core::ops::function::FnOnce::call_once::h87f1c6a27ccaa412 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x55cf249aef33 - core::ops::function::FnOnce::call_once::ha61d5393ae51ae22 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 20: 0x55cf249aef33 - test::__rust_begin_short_backtrace::h4a8691b5281c1f3b [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:516:5 [INFO] [stdout] 21: 0x55cf249ad826 - as core::ops::function::FnOnce>::call_once::hd25ba61494055ad3 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 22: 0x55cf249ad826 - as core::ops::function::FnOnce<()>>::call_once::h052fde7b846767ba [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:322:9 [INFO] [stdout] 23: 0x55cf249ad826 - std::panicking::try::do_call::h04571a1da6838ba1 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:379:40 [INFO] [stdout] 24: 0x55cf249ad826 - std::panicking::try::h758f090614125cbe [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:343:19 [INFO] [stdout] 25: 0x55cf249ad826 - std::panic::catch_unwind::h0c48513d5a021d3c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:396:14 [INFO] [stdout] 26: 0x55cf249ad826 - test::run_test_in_process::hee75babaaac3822f [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:538:18 [INFO] [stdout] 27: 0x55cf249ad826 - test::run_test::run_test_inner::{{closure}}::h2920dea75d7f0c1e [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:449:39 [INFO] [stdout] 28: 0x55cf24985b36 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha23dff608830db13 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 29: 0x55cf2498a8cd - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hacce322dead3a799 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/thread/mod.rs:474:17 [INFO] [stdout] 30: 0x55cf2498a8cd - as core::ops::function::FnOnce<()>>::call_once::h4f0bc9c26ead0a27 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:322:9 [INFO] [stdout] 31: 0x55cf2498a8cd - std::panicking::try::do_call::h1b9f282fb0c383b3 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:379:40 [INFO] [stdout] 32: 0x55cf2498a8cd - std::panicking::try::hed1ebed51b133ee4 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:343:19 [INFO] [stdout] 33: 0x55cf2498a8cd - std::panic::catch_unwind::h072a2b19fd39a474 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:396:14 [INFO] [stdout] 34: 0x55cf2498a8cd - std::thread::Builder::spawn_unchecked::{{closure}}::hb02c6b2d3ff18bc2 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/thread/mod.rs:473:30 [INFO] [stdout] 35: 0x55cf2498a8cd - core::ops::function::FnOnce::call_once{{vtable.shim}}::hb512c5d27b4adb83 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 36: 0x55cf24a1648a - as core::ops::function::FnOnce>::call_once::hdbb9ff71143eaad6 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 37: 0x55cf24a1648a - as core::ops::function::FnOnce>::call_once::h9dbde95cefb8368c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 38: 0x55cf24a1648a - std::sys::unix::thread::Thread::new::thread_start::hc76032c38affd972 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys/unix/thread.rs:71:17 [INFO] [stdout] 39: 0x7f8ba6ef5609 - start_thread [INFO] [stdout] 40: 0x7f8ba6cc7293 - clone [INFO] [stdout] 41: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- test_interval_at_twice stdout ---- [INFO] [stdout] thread 'test_interval_at_twice' panicked at 'expect=800ms; actual=779.760904ms', tests/support.rs:24:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55cf24a0de77 - std::backtrace_rs::backtrace::libunwind::trace::hcd6e187547b8deec [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x55cf24a0de77 - std::backtrace_rs::backtrace::trace_unsynchronized::hde10036eb783fbc7 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55cf24a0de77 - std::sys_common::backtrace::_print_fmt::h63987b8f86dfc82a [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x55cf24a0de77 - ::fmt::hc33f3ac4cc9c4196 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x55cf24a2dfdc - core::fmt::write::hfdcdeb08fb2b3afa [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/fmt/mod.rs:1078:17 [INFO] [stdout] 5: 0x55cf24a09f82 - std::io::Write::write_fmt::h28c53dc51251a293 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/io/mod.rs:1519:15 [INFO] [stdout] 6: 0x55cf24a10175 - std::sys_common::backtrace::_print::hf179508b01f66878 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x55cf24a10175 - std::sys_common::backtrace::print::ha97d11adb39266a6 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x55cf24a10175 - std::panicking::default_hook::{{closure}}::h8da9985a98bd1582 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x55cf24a0fd02 - std::panicking::default_hook::hbc0ee792993c2ef2 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x55cf24a10911 - std::panicking::rust_panic_with_hook::h9fb9cda33a10f084 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:591:17 [INFO] [stdout] 11: 0x55cf24a10457 - std::panicking::begin_panic_handler::{{closure}}::hf3e83396e085962e [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:497:13 [INFO] [stdout] 12: 0x55cf24a0e33c - std::sys_common::backtrace::__rust_end_short_backtrace::hac151ac4c1e3252d [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x55cf24a103b9 - rust_begin_unwind [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:493:5 [INFO] [stdout] 14: 0x55cf24a1036b - std::panicking::begin_panic_fmt::h01a0df196b065ae4 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:435:5 [INFO] [stdout] 15: 0x55cf24975aad - test_timer::support::Elapsed::assert_is_about::h7c29c53b89c14f91 [INFO] [stdout] at /opt/rustwide/workdir/tests/support.rs:24:13 [INFO] [stdout] 16: 0x55cf249607a9 - test_timer::test_interval_at_twice::h897f06b79e3c6d21 [INFO] [stdout] at /opt/rustwide/workdir/tests/test_timer.rs:295:5 [INFO] [stdout] 17: 0x55cf249605ea - test_timer::test_interval_at_twice::{{closure}}::h210fcbf16545c1bb [INFO] [stdout] at /opt/rustwide/workdir/tests/test_timer.rs:284:1 [INFO] [stdout] 18: 0x55cf2497c75e - core::ops::function::FnOnce::call_once::h6a8884b4edfe76e7 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x55cf249aef33 - core::ops::function::FnOnce::call_once::ha61d5393ae51ae22 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 20: 0x55cf249aef33 - test::__rust_begin_short_backtrace::h4a8691b5281c1f3b [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:516:5 [INFO] [stdout] 21: 0x55cf249ad826 - as core::ops::function::FnOnce>::call_once::hd25ba61494055ad3 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 22: 0x55cf249ad826 - as core::ops::function::FnOnce<()>>::call_once::h052fde7b846767ba [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:322:9 [INFO] [stdout] 23: 0x55cf249ad826 - std::panicking::try::do_call::h04571a1da6838ba1 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:379:40 [INFO] [stdout] 24: 0x55cf249ad826 - std::panicking::try::h758f090614125cbe [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:343:19 [INFO] [stdout] 25: 0x55cf249ad826 - std::panic::catch_unwind::h0c48513d5a021d3c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:396:14 [INFO] [stdout] 26: 0x55cf249ad826 - test::run_test_in_process::hee75babaaac3822f [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:538:18 [INFO] [stdout] 27: 0x55cf249ad826 - test::run_test::run_test_inner::{{closure}}::h2920dea75d7f0c1e [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/test/src/lib.rs:449:39 [INFO] [stdout] 28: 0x55cf24985b36 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha23dff608830db13 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 29: 0x55cf2498a8cd - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hacce322dead3a799 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/thread/mod.rs:474:17 [INFO] [stdout] 30: 0x55cf2498a8cd - as core::ops::function::FnOnce<()>>::call_once::h4f0bc9c26ead0a27 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:322:9 [INFO] [stdout] 31: 0x55cf2498a8cd - std::panicking::try::do_call::h1b9f282fb0c383b3 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:379:40 [INFO] [stdout] 32: 0x55cf2498a8cd - std::panicking::try::hed1ebed51b133ee4 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panicking.rs:343:19 [INFO] [stdout] 33: 0x55cf2498a8cd - std::panic::catch_unwind::h072a2b19fd39a474 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/panic.rs:396:14 [INFO] [stdout] 34: 0x55cf2498a8cd - std::thread::Builder::spawn_unchecked::{{closure}}::hb02c6b2d3ff18bc2 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/thread/mod.rs:473:30 [INFO] [stdout] 35: 0x55cf2498a8cd - core::ops::function::FnOnce::call_once{{vtable.shim}}::hb512c5d27b4adb83 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 36: 0x55cf24a1648a - as core::ops::function::FnOnce>::call_once::hdbb9ff71143eaad6 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 37: 0x55cf24a1648a - as core::ops::function::FnOnce>::call_once::h9dbde95cefb8368c [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/alloc/src/boxed.rs:1328:9 [INFO] [stdout] 38: 0x55cf24a1648a - std::sys::unix::thread::Thread::new::thread_start::hc76032c38affd972 [INFO] [stdout] at /rustc/25b3db3aa752d9d4633c983c9d4bf5e48f9baff1/library/std/src/sys/unix/thread.rs:71:17 [INFO] [stdout] 39: 0x7f8ba6ef5609 - start_thread [INFO] [stdout] 40: 0x7f8ba6cc7293 - clone [INFO] [stdout] 41: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test_interval_at_twice [INFO] [stdout] test_interval_once [INFO] [stdout] test_interval_twice [INFO] [stdout] [INFO] [stdout] test result: FAILED. 12 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.08s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "f7de8ec9c17bc7b74eb5af5cbd136f0a0b707cd8a882e1a163215f28ad199c7d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f7de8ec9c17bc7b74eb5af5cbd136f0a0b707cd8a882e1a163215f28ad199c7d", kill_on_drop: false }` [INFO] [stdout] f7de8ec9c17bc7b74eb5af5cbd136f0a0b707cd8a882e1a163215f28ad199c7d