[INFO] fetching crate dyn-timeout 0.3.3...
[INFO] testing dyn-timeout-0.3.3 against master#7704328ba5ae8d6ce0ac303c9d5a1a1605906766 for pr-146989
[INFO] extracting crate dyn-timeout 0.3.3 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate dyn-timeout 0.3.3
[INFO] finished tweaking crates.io crate dyn-timeout 0.3.3
[INFO] tweaked toml for crates.io crate dyn-timeout 0.3.3 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate dyn-timeout 0.3.3 on toolchain 7704328ba5ae8d6ce0ac303c9d5a1a1605906766
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 36 packages to latest compatible versions
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded socket2 v0.6.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9d7c7ccc68c305292c27f4a821e83aa48274736393b4f64caa4e2fbf7fadc522
[INFO] running `Command { std: "docker" "start" "-a" "9d7c7ccc68c305292c27f4a821e83aa48274736393b4f64caa4e2fbf7fadc522", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9d7c7ccc68c305292c27f4a821e83aa48274736393b4f64caa4e2fbf7fadc522", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9d7c7ccc68c305292c27f4a821e83aa48274736393b4f64caa4e2fbf7fadc522", kill_on_drop: false }`
[INFO] [stdout] 9d7c7ccc68c305292c27f4a821e83aa48274736393b4f64caa4e2fbf7fadc522
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 787bc89837737411cbc13642fbf291034d3bdb3240c6bb18e9cbeb4352b81c9b
[INFO] running `Command { std: "docker" "start" "-a" "787bc89837737411cbc13642fbf291034d3bdb3240c6bb18e9cbeb4352b81c9b", kill_on_drop: false }`
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling dyn-timeout v0.3.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.59s
[INFO] running `Command { std: "docker" "inspect" "787bc89837737411cbc13642fbf291034d3bdb3240c6bb18e9cbeb4352b81c9b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "787bc89837737411cbc13642fbf291034d3bdb3240c6bb18e9cbeb4352b81c9b", kill_on_drop: false }`
[INFO] [stdout] 787bc89837737411cbc13642fbf291034d3bdb3240c6bb18e9cbeb4352b81c9b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9d5b0c316f1c554735ab9026c10c8dea64ee8a2cdaa0047f1819a0b7d6cfbd75
[INFO] running `Command { std: "docker" "start" "-a" "9d5b0c316f1c554735ab9026c10c8dea64ee8a2cdaa0047f1819a0b7d6cfbd75", kill_on_drop: false }`
[INFO] [stderr]    Compiling dyn-timeout v0.3.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.49s
[INFO] running `Command { std: "docker" "inspect" "9d5b0c316f1c554735ab9026c10c8dea64ee8a2cdaa0047f1819a0b7d6cfbd75", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9d5b0c316f1c554735ab9026c10c8dea64ee8a2cdaa0047f1819a0b7d6cfbd75", kill_on_drop: false }`
[INFO] [stdout] 9d5b0c316f1c554735ab9026c10c8dea64ee8a2cdaa0047f1819a0b7d6cfbd75
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e6395eb691159d71b7a217933ff51248a44be0a32c083695c7cd5f2b1599b286
[INFO] running `Command { std: "docker" "start" "-a" "e6395eb691159d71b7a217933ff51248a44be0a32c083695c7cd5f2b1599b286", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/dyn_timeout-1caf40592dc4a4a9)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test::tokio_test ... ok
[INFO] [stdout] test test::cancel_test ... ok
[INFO] [stdout] test test::simple_test ... ok
[INFO] [stdout] test test::tokio_test_bench ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests dyn_timeout
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test src/tokio_impl.rs - tokio_impl::DynTimeout::cancel (line 233) ... ok
[INFO] [stdout] test src/std_thread.rs - std_thread::DynTimeout::new (line 42) ... FAILED
[INFO] [stdout] test src/std_thread.rs - std_thread::DynTimeout::cancel (line 154) ... ok
[INFO] [stdout] test src/std_thread.rs - std_thread::DynTimeout::sub (line 111) ... FAILED
[INFO] [stdout] test src/std_thread.rs - std_thread::DynTimeout::add (line 81) ... ok
[INFO] [stdout] test src/std_thread.rs - std_thread::DynTimeout (line 17) ... ok
[INFO] [stdout] test src/tokio_impl.rs - tokio_impl::DynTimeout::add (line 155) ... ok
[INFO] [stdout] test src/tokio_impl.rs - tokio_impl::DynTimeout (line 22) ... ok
[INFO] [stdout] test src/tokio_impl.rs - tokio_impl::DynTimeout::with_sender (line 99) ... ok
[INFO] [stdout] test src/tokio_impl.rs - tokio_impl::DynTimeout::sub (line 193) ... ok
[INFO] [stdout] test src/tokio_impl.rs - tokio_impl::DynTimeout::new (line 50) ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/std_thread.rs - std_thread::DynTimeout::new (line 42) stdout ----
[INFO] [stdout] Test executable failed (exit status: 101).
[INFO] [stdout] 
[INFO] [stdout] stdout:
[INFO] [stdout] after forty milliseconds
[INFO] [stdout] 
[INFO] [stdout] stderr:
[INFO] [stdout] 
[INFO] [stdout] thread 'main' (179) panicked at src/std_thread.rs:13:25:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Timeout already reached
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<&str>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.100/src/backtrace.rs:27:14
[INFO] [stdout]    1: anyhow::__private::format_err
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.100/src/lib.rs:695:13
[INFO] [stdout]    2: <dyn_timeout::std_thread::DynTimeout>::add
[INFO] [stdout]              at ./src/std_thread.rs:95:21
[INFO] [stdout]    3: rust_out::main::_doctest_main_src_std_thread_rs_42_0
[INFO] [stdout]    4: rust_out::main
[INFO] [stdout]    5: <fn() as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    6: std::sys::backtrace::__rust_begin_short_backtrace::<fn(), ()>
[INFO] [stdout]    7: std::rt::lang_start::<()>::{closure#0}
[INFO] [stdout]    8: <&dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:287:21
[INFO] [stdout]    9: std::panicking::catch_unwind::do_call::<&dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync, i32>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   10: std::panicking::catch_unwind::<i32, &dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   11: std::panic::catch_unwind::<&dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync, i32>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   12: std::rt::lang_start_internal::{closure#0}
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:175:24
[INFO] [stdout]   13: std::panicking::catch_unwind::do_call::<std::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   14: std::panicking::catch_unwind::<isize, std::rt::lang_start_internal::{closure#0}>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   15: std::panic::catch_unwind::<std::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   16: std::rt::lang_start_internal
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:171:5
[INFO] [stdout]   17: std::rt::lang_start::<()>
[INFO] [stdout]   18: main
[INFO] [stdout]   19: <unknown>
[INFO] [stdout]   20: __libc_start_main
[INFO] [stdout]   21: _start
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5bf11280cb82 - std[ed5b486fa4cdb954]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5bf11280cb82 - std[ed5b486fa4cdb954]::backtrace_rs::backtrace::trace_unsynchronized::<std[ed5b486fa4cdb954]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5bf11280cb82 - std[ed5b486fa4cdb954]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5bf11280cb82 - <<std[ed5b486fa4cdb954]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[ab67b43d93431064]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5bf11281efda - <core[ab67b43d93431064]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5bf11281efda - core[ab67b43d93431064]::fmt::write
[INFO] [stdout]    6:     0x5bf112811766 - std[ed5b486fa4cdb954]::io::default_write_fmt::<std[ed5b486fa4cdb954]::sys::stdio::unix::Stderr>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5bf112811766 - <std[ed5b486fa4cdb954]::sys::stdio::unix::Stderr as std[ed5b486fa4cdb954]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5bf1127f60ef - <std[ed5b486fa4cdb954]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5bf1127f60ef - std[ed5b486fa4cdb954]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5bf1128084d1 - std[ed5b486fa4cdb954]::panicking::default_hook
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:319:9
[INFO] [stdout]   11:     0x5bf11280868b - std[ed5b486fa4cdb954]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:825:13
[INFO] [stdout]   12:     0x5bf1127f61a8 - std[ed5b486fa4cdb954]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:698:13
[INFO] [stdout]   13:     0x5bf1127ed8f9 - std[ed5b486fa4cdb954]::sys::backtrace::__rust_end_short_backtrace::<std[ed5b486fa4cdb954]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   14:     0x5bf1127f6b3d - __rustc[5067f77124470312]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:689:5
[INFO] [stdout]   15:     0x5bf11281f6ec - core[ab67b43d93431064]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/panicking.rs:80:14
[INFO] [stdout]   16:     0x5bf11281f462 - core[ab67b43d93431064]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/result.rs:1867:5
[INFO] [stdout]   17:     0x5bf1127c1194 - rust_out[12d7c3eb12d5b871]::main::_doctest_main_src_std_thread_rs_42_0
[INFO] [stdout]   18:     0x5bf1127c1096 - rust_out[12d7c3eb12d5b871]::main
[INFO] [stdout]   19:     0x5bf1127c1223 - <fn() as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5bf1127c1036 - std[ed5b486fa4cdb954]::sys::backtrace::__rust_begin_short_backtrace::<fn(), ()>
[INFO] [stdout]   21:     0x5bf1127c1049 - std[ed5b486fa4cdb954]::rt::lang_start::<()>::{closure#0}
[INFO] [stdout]   22:     0x5bf112807ac6 - <&dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:287:21
[INFO] [stdout]   23:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::panicking::catch_unwind::do_call::<&dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync, i32>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   24:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::panicking::catch_unwind::<i32, &dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   25:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::panic::catch_unwind::<&dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync, i32>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   26:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:175:24
[INFO] [stdout]   27:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::panicking::catch_unwind::do_call::<std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::panicking::catch_unwind::<isize, std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::panic::catch_unwind::<std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x5bf112807ac6 - std[ed5b486fa4cdb954]::rt::lang_start_internal
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:171:5
[INFO] [stdout]   31:     0x5bf1127c1021 - std[ed5b486fa4cdb954]::rt::lang_start::<()>
[INFO] [stdout]   32:     0x5bf1127c12b5 - main
[INFO] [stdout]   33:     0x751c97f5b1ca - <unknown>
[INFO] [stdout]   34:     0x751c97f5b28b - __libc_start_main
[INFO] [stdout]   35:     0x5bf1127c0e35 - _start
[INFO] [stdout]   36:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] ---- src/std_thread.rs - std_thread::DynTimeout::sub (line 111) stdout ----
[INFO] [stdout] Test executable failed (exit status: 101).
[INFO] [stdout] 
[INFO] [stdout] stdout:
[INFO] [stdout] after some milliseconds
[INFO] [stdout] 
[INFO] [stdout] stderr:
[INFO] [stdout] 
[INFO] [stdout] thread 'main' (206) panicked at src/std_thread.rs:14:22:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Timeout already reached
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<&str>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.100/src/backtrace.rs:27:14
[INFO] [stdout]    1: anyhow::__private::format_err
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.100/src/lib.rs:695:13
[INFO] [stdout]    2: <dyn_timeout::std_thread::DynTimeout>::add
[INFO] [stdout]              at ./src/std_thread.rs:95:21
[INFO] [stdout]    3: rust_out::main::_doctest_main_src_std_thread_rs_111_0
[INFO] [stdout]    4: rust_out::main
[INFO] [stdout]    5: <fn() as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]    6: std::sys::backtrace::__rust_begin_short_backtrace::<fn(), ()>
[INFO] [stdout]    7: std::rt::lang_start::<()>::{closure#0}
[INFO] [stdout]    8: <&dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:287:21
[INFO] [stdout]    9: std::panicking::catch_unwind::do_call::<&dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync, i32>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   10: std::panicking::catch_unwind::<i32, &dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   11: std::panic::catch_unwind::<&dyn core::ops::function::Fn<(), Output = i32> + core::panic::unwind_safe::RefUnwindSafe + core::marker::Sync, i32>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   12: std::rt::lang_start_internal::{closure#0}
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:175:24
[INFO] [stdout]   13: std::panicking::catch_unwind::do_call::<std::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   14: std::panicking::catch_unwind::<isize, std::rt::lang_start_internal::{closure#0}>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   15: std::panic::catch_unwind::<std::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   16: std::rt::lang_start_internal
[INFO] [stdout]              at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:171:5
[INFO] [stdout]   17: std::rt::lang_start::<()>
[INFO] [stdout]   18: main
[INFO] [stdout]   19: <unknown>
[INFO] [stdout]   20: __libc_start_main
[INFO] [stdout]   21: _start
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5939e82a8be2 - std[ed5b486fa4cdb954]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5939e82a8be2 - std[ed5b486fa4cdb954]::backtrace_rs::backtrace::trace_unsynchronized::<std[ed5b486fa4cdb954]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5939e82a8be2 - std[ed5b486fa4cdb954]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5939e82a8be2 - <<std[ed5b486fa4cdb954]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[ab67b43d93431064]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5939e82bb03a - <core[ab67b43d93431064]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5939e82bb03a - core[ab67b43d93431064]::fmt::write
[INFO] [stdout]    6:     0x5939e82ad7c6 - std[ed5b486fa4cdb954]::io::default_write_fmt::<std[ed5b486fa4cdb954]::sys::stdio::unix::Stderr>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5939e82ad7c6 - <std[ed5b486fa4cdb954]::sys::stdio::unix::Stderr as std[ed5b486fa4cdb954]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5939e829214f - <std[ed5b486fa4cdb954]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5939e829214f - std[ed5b486fa4cdb954]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5939e82a4531 - std[ed5b486fa4cdb954]::panicking::default_hook
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:319:9
[INFO] [stdout]   11:     0x5939e82a46eb - std[ed5b486fa4cdb954]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:825:13
[INFO] [stdout]   12:     0x5939e8292208 - std[ed5b486fa4cdb954]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:698:13
[INFO] [stdout]   13:     0x5939e8289959 - std[ed5b486fa4cdb954]::sys::backtrace::__rust_end_short_backtrace::<std[ed5b486fa4cdb954]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   14:     0x5939e8292b9d - __rustc[5067f77124470312]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:689:5
[INFO] [stdout]   15:     0x5939e82bb74c - core[ab67b43d93431064]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/panicking.rs:80:14
[INFO] [stdout]   16:     0x5939e82bb4c2 - core[ab67b43d93431064]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/result.rs:1867:5
[INFO] [stdout]   17:     0x5939e825c4da - rust_out[12d7c3eb12d5b871]::main::_doctest_main_src_std_thread_rs_111_0
[INFO] [stdout]   18:     0x5939e825c3a6 - rust_out[12d7c3eb12d5b871]::main
[INFO] [stdout]   19:     0x5939e825c723 - <fn() as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5939e825c346 - std[ed5b486fa4cdb954]::sys::backtrace::__rust_begin_short_backtrace::<fn(), ()>
[INFO] [stdout]   21:     0x5939e825c359 - std[ed5b486fa4cdb954]::rt::lang_start::<()>::{closure#0}
[INFO] [stdout]   22:     0x5939e82a3b26 - <&dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:287:21
[INFO] [stdout]   23:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::panicking::catch_unwind::do_call::<&dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync, i32>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   24:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::panicking::catch_unwind::<i32, &dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   25:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::panic::catch_unwind::<&dyn core[ab67b43d93431064]::ops::function::Fn<(), Output = i32> + core[ab67b43d93431064]::panic::unwind_safe::RefUnwindSafe + core[ab67b43d93431064]::marker::Sync, i32>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   26:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:175:24
[INFO] [stdout]   27:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::panicking::catch_unwind::do_call::<std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::panicking::catch_unwind::<isize, std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::panic::catch_unwind::<std[ed5b486fa4cdb954]::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x5939e82a3b26 - std[ed5b486fa4cdb954]::rt::lang_start_internal
[INFO] [stdout]                                at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/rt.rs:171:5
[INFO] [stdout]   31:     0x5939e825c331 - std[ed5b486fa4cdb954]::rt::lang_start::<()>
[INFO] [stdout]   32:     0x5939e825c7b5 - main
[INFO] [stdout]   33:     0x776779a861ca - <unknown>
[INFO] [stdout]   34:     0x776779a8628b - __libc_start_main
[INFO] [stdout]   35:     0x5939e825c145 - _start
[INFO] [stdout]   36:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/std_thread.rs - std_thread::DynTimeout::new (line 42)
[INFO] [stdout]     src/std_thread.rs - std_thread::DynTimeout::sub (line 111)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 9 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.21s
[INFO] [stdout] 
[INFO] [stderr] error: doctest failed, to rerun pass `--doc`
[INFO] running `Command { std: "docker" "inspect" "e6395eb691159d71b7a217933ff51248a44be0a32c083695c7cd5f2b1599b286", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e6395eb691159d71b7a217933ff51248a44be0a32c083695c7cd5f2b1599b286", kill_on_drop: false }`
[INFO] [stdout] e6395eb691159d71b7a217933ff51248a44be0a32c083695c7cd5f2b1599b286