[INFO] fetching crate checklist 0.3.0...
[INFO] testing checklist-0.3.0 against master#b70888601af92f6cdc0364abab3446e418b91d36 for pr-86479
[INFO] extracting crate checklist 0.3.0 into /workspace/builds/worker-3/source
[INFO] validating manifest of crates.io crate checklist 0.3.0 on toolchain b70888601af92f6cdc0364abab3446e418b91d36
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate checklist 0.3.0
[INFO] finished tweaking crates.io crate checklist 0.3.0
[INFO] tweaked toml for crates.io crate checklist 0.3.0 written to /workspace/builds/worker-3/source/Cargo.toml
[INFO] crate crates.io crate checklist 0.3.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 182b81f80d23533ca5bc23b98fc11b1ff74cef73cb939f76ff750fa98f561a29
[INFO] running `Command { std: "docker" "start" "-a" "182b81f80d23533ca5bc23b98fc11b1ff74cef73cb939f76ff750fa98f561a29", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "182b81f80d23533ca5bc23b98fc11b1ff74cef73cb939f76ff750fa98f561a29", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "182b81f80d23533ca5bc23b98fc11b1ff74cef73cb939f76ff750fa98f561a29", kill_on_drop: false }`
[INFO] [stdout] 182b81f80d23533ca5bc23b98fc11b1ff74cef73cb939f76ff750fa98f561a29
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7e9bf500ceb404bd25321ef20156ddea0191863aaef22660051639168c8ed951
[INFO] running `Command { std: "docker" "start" "-a" "7e9bf500ceb404bd25321ef20156ddea0191863aaef22660051639168c8ed951", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling syn v1.0.33
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling getrandom v0.1.14
[INFO] [stderr]    Compiling memchr v2.3.3
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling regex-syntax v0.6.18
[INFO] [stderr]    Compiling unicode-segmentation v1.6.0
[INFO] [stderr]    Compiling gimli v0.22.0
[INFO] [stderr]    Compiling failure_derive v0.1.8
[INFO] [stderr]    Compiling serde v1.0.114
[INFO] [stderr]    Compiling serde_derive v1.0.114
[INFO] [stderr]    Compiling once_cell v1.4.0
[INFO] [stderr]    Compiling rustc-demangle v0.1.16
[INFO] [stderr]    Compiling object v0.20.0
[INFO] [stderr]    Compiling linked-hash-map v0.5.3
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.3
[INFO] [stderr]    Compiling proc-macro-error v1.0.3
[INFO] [stderr]    Compiling proc-macro2 v1.0.18
[INFO] [stderr]    Compiling termios v0.3.2
[INFO] [stderr]    Compiling terminal_size v0.1.13
[INFO] [stderr]    Compiling os_pipe v0.9.2
[INFO] [stderr]    Compiling shared_child v0.3.4
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling yaml-rust v0.4.4
[INFO] [stderr]    Compiling duct v0.13.4
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling clap v2.33.1
[INFO] [stderr]    Compiling quote v1.0.7
[INFO] [stderr]    Compiling duct_sh v0.13.4
[INFO] [stderr]    Compiling aho-corasick v0.7.13
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling tempfile v3.1.0
[INFO] [stderr]    Compiling addr2line v0.13.0
[INFO] [stderr]    Compiling regex v1.3.9
[INFO] [stderr]    Compiling backtrace v0.3.50
[INFO] [stderr]    Compiling console v0.11.3
[INFO] [stderr]    Compiling indicatif v0.15.0
[INFO] [stderr]    Compiling dialoguer v0.6.2
[INFO] [stderr]    Compiling serde_yaml v0.8.13
[INFO] [stderr]    Compiling syn-mid v0.5.0
[INFO] [stderr]    Compiling synstructure v0.12.4
[INFO] [stderr]    Compiling failure v0.1.8
[INFO] [stderr]    Compiling structopt-derive v0.4.8
[INFO] [stderr]    Compiling structopt v0.3.15
[INFO] [stderr]    Compiling checklist v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 30.71s
[INFO] running `Command { std: "docker" "inspect" "7e9bf500ceb404bd25321ef20156ddea0191863aaef22660051639168c8ed951", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7e9bf500ceb404bd25321ef20156ddea0191863aaef22660051639168c8ed951", kill_on_drop: false }`
[INFO] [stdout] 7e9bf500ceb404bd25321ef20156ddea0191863aaef22660051639168c8ed951
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7efff8ad671db692bd4856f4797a82f419cf23ba36b2d1e04145f39d20fe7859
[INFO] running `Command { std: "docker" "start" "-a" "7efff8ad671db692bd4856f4797a82f419cf23ba36b2d1e04145f39d20fe7859", kill_on_drop: false }`
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling void v1.0.2
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling walkdir v2.3.1
[INFO] [stderr]    Compiling error-chain v0.12.2
[INFO] [stderr]    Compiling log v0.4.8
[INFO] [stderr]    Compiling bstr v0.2.13
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling nix v0.14.1
[INFO] [stderr]    Compiling globset v0.4.5
[INFO] [stderr]    Compiling ignore v0.4.16
[INFO] [stderr]    Compiling float-cmp v0.6.0
[INFO] [stderr]    Compiling predicates v1.0.4
[INFO] [stderr]    Compiling globwalk v0.7.3
[INFO] [stderr]    Compiling assert_cmd v1.0.1
[INFO] [stderr]    Compiling assert_fs v1.0.0
[INFO] [stderr]    Compiling rexpect v0.4.0
[INFO] [stderr]    Compiling checklist v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.01s
[INFO] running `Command { std: "docker" "inspect" "7efff8ad671db692bd4856f4797a82f419cf23ba36b2d1e04145f39d20fe7859", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7efff8ad671db692bd4856f4797a82f419cf23ba36b2d1e04145f39d20fe7859", kill_on_drop: false }`
[INFO] [stdout] 7efff8ad671db692bd4856f4797a82f419cf23ba36b2d1e04145f39d20fe7859
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7d0ca7bfce07d6ebc75907102ab4fef6a09c0d10d2c1be1dfda10c1180f886cf
[INFO] running `Command { std: "docker" "start" "-a" "7d0ca7bfce07d6ebc75907102ab4fef6a09c0d10d2c1be1dfda10c1180f886cf", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/checklist-a28402c60775fa67)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test tests::test_return_code_fail ... ok
[INFO] [stdout] test tests::test_return_code ... ok
[INFO] [stdout] test tests::test_invalid_filename ... ok
[INFO] [stdout] test tests::test_incorrect_yaml ... ok
[INFO] [stdout] test tests::test_correct_yaml ... ok
[INFO] [stdout] test tests::test_incorrect_schema_version ... ok
[INFO] [stdout] test tests::test_defaults ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/checklist-a92591dbc6f55248)
[INFO] [stdout] 
[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] [stderr]      Running tests/rexpect_integration_tests.rs (/opt/rustwide/target/debug/deps/rexpect_integration_tests-67410d1c156f726f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_multi_rejection ... FAILED
[INFO] [stdout] test test_multi_success ... FAILED
[INFO] [stdout] test test_basic_usage ... FAILED
[INFO] [stdout] test test_basic_rejection ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_multi_rejection stdout ----
[INFO] [stdout] thread 'test_multi_rejection' panicked at 'called `Result::unwrap()` on an `Err` value: Error(Timeout("EOF (End of File)", "`\\r``\\n`\n", 2s), State { next_error: None, backtrace: InternalBacktrace { backtrace: Some(   0: error_chain::backtrace::imp::InternalBacktrace::new
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/backtrace.rs:56
[INFO] [stdout]    1: <error_chain::State as core::default::Default>::default
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/lib.rs:687
[INFO] [stdout]    2: rexpect::errors::Error::from_kind
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:269
[INFO] [stdout]    3: <rexpect::errors::Error as core::convert::From<rexpect::errors::ErrorKind>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/convert/mod.rs:540
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/reader.rs:245
[INFO] [stdout]    6: rexpect::session::StreamSession<W>::exp
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:103
[INFO] [stdout]    7: rexpect::session::StreamSession<W>::exp_eof
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:109
[INFO] [stdout]    8: rexpect_integration_tests::test_multi_rejection
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:136
[INFO] [stdout]    9: rexpect_integration_tests::test_multi_rejection::{{closure}}
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:111
[INFO] [stdout]   10: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   15: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91
[INFO] [stdout]   16: start_thread
[INFO] [stdout]   17: clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:136:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x555c79229030 - std::backtrace_rs::backtrace::libunwind::trace::h99dbb39dca18857d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5
[INFO] [stdout]    1:     0x555c79229030 - std::backtrace_rs::backtrace::trace_unsynchronized::h832861927e9cfedf
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x555c79229030 - std::sys_common::backtrace::_print_fmt::h3d18154c77dcf310
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x555c79229030 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::he312f4ad5b9bb346
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x555c7924cfbc - core::fmt::write::h9a6d9c74526a6c1b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/fmt/mod.rs:1115:17
[INFO] [stdout]    5:     0x555c79225725 - std::io::Write::write_fmt::h65d36b96410d80d7
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/io/mod.rs:1665:15
[INFO] [stdout]    6:     0x555c7922b65b - std::sys_common::backtrace::_print::h65d996766de40da4
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x555c7922b65b - std::sys_common::backtrace::print::h40df9727e635f303
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x555c7922b65b - std::panicking::default_hook::{{closure}}::hd2da4327dea91a51
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:208:50
[INFO] [stdout]    9:     0x555c7922b16c - std::panicking::default_hook::h3d55120ad6ada158
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:222:9
[INFO] [stdout]   10:     0x555c7922bd24 - std::panicking::rust_panic_with_hook::hf85dd0bb545e3b55
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:622:17
[INFO] [stdout]   11:     0x555c7922b807 - std::panicking::begin_panic_handler::{{closure}}::h736ae969434da9fa
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:519:13
[INFO] [stdout]   12:     0x555c7922950c - std::sys_common::backtrace::__rust_end_short_backtrace::h6133bb80b1d6c3e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:141:18
[INFO] [stdout]   13:     0x555c7922b769 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:515:5
[INFO] [stdout]   14:     0x555c78edc8e1 - core::panicking::panic_fmt::hcf5f6d96e1dd7099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/panicking.rs:92:14
[INFO] [stdout]   15:     0x555c78edc9d3 - core::result::unwrap_failed::he898b02f57993c42
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1599:5
[INFO] [stdout]   16:     0x555c78ee0f03 - core::result::Result<T,E>::unwrap::h9c1365770168b099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1281:23
[INFO] [stdout]   17:     0x555c78edef4a - rexpect_integration_tests::test_multi_rejection::h403a3b28ce27e02f
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:136:5
[INFO] [stdout]   18:     0x555c78ede85a - rexpect_integration_tests::test_multi_rejection::{{closure}}::hc1878c2fcf6e8e41
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:111:1
[INFO] [stdout]   19:     0x555c78ee084e - core::ops::function::FnOnce::call_once::hc5ec6c2e47c93b08
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x555c78f15223 - core::ops::function::FnOnce::call_once::h227fa683a5745bd3
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x555c78f15223 - test::__rust_begin_short_backtrace::h3a193bd570ada00a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578:5
[INFO] [stdout]   22:     0x555c78f13c98 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h262d2f411717179a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   23:     0x555c78f13c98 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfec00caec3e52a7b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   24:     0x555c78f13c98 - std::panicking::try::do_call::ha5f42e76ffda569d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   25:     0x555c78f13c98 - std::panicking::try::h53f36df0935b9500
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   26:     0x555c78f13c98 - std::panic::catch_unwind::h5a497f74b0ce006f
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   27:     0x555c78f13c98 - test::run_test_in_process::hab0aead337f48a88
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601:18
[INFO] [stdout]   28:     0x555c78f13c98 - test::run_test::run_test_inner::{{closure}}::h632c383164d4cba2
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493:39
[INFO] [stdout]   29:     0x555c78ee241d - test::run_test::run_test_inner::{{closure}}::h7c4b3612891a4b58
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520:37
[INFO] [stdout]   30:     0x555c78ee241d - std::sys_common::backtrace::__rust_begin_short_backtrace::habf7d8a08419d00e
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125:18
[INFO] [stdout]   31:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h3acba502e2068204
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476:17
[INFO] [stdout]   32:     0x555c78ee6b48 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h526ef819c1964a35
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   33:     0x555c78ee6b48 - std::panicking::try::do_call::hc0b5663d6373cec8
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   34:     0x555c78ee6b48 - std::panicking::try::ha9007ede9d35dcd6
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   35:     0x555c78ee6b48 - std::panic::catch_unwind::hf60d56329ebbb81c
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   36:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::hb1722d498f4ce777
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475:30
[INFO] [stdout]   37:     0x555c78ee6b48 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h15eb01325730c962
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h09f167e08fc945ff
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   39:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf814fe85eeb118e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   40:     0x555c79231787 - std::sys::unix::thread::Thread::new::thread_start::hb71b17934c5f5e68
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91:17
[INFO] [stdout]   41:     0x7fcb0ee1d609 - start_thread
[INFO] [stdout]   42:     0x7fcb0ef85293 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_multi_success stdout ----
[INFO] [stdout] thread 'test_multi_success' panicked at 'called `Result::unwrap()` on an `Err` value: Error(Timeout("EOF (End of File)", "`\\r``\\n`\n", 2s), State { next_error: None, backtrace: InternalBacktrace { backtrace: Some(   0: error_chain::backtrace::imp::InternalBacktrace::new
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/backtrace.rs:56
[INFO] [stdout]    1: <error_chain::State as core::default::Default>::default
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/lib.rs:687
[INFO] [stdout]    2: rexpect::errors::Error::from_kind
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:269
[INFO] [stdout]    3: <rexpect::errors::Error as core::convert::From<rexpect::errors::ErrorKind>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/convert/mod.rs:540
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/reader.rs:245
[INFO] [stdout]    6: rexpect::session::StreamSession<W>::exp
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:103
[INFO] [stdout]    7: rexpect::session::StreamSession<W>::exp_eof
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:109
[INFO] [stdout]    8: rexpect_integration_tests::test_multi_success
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:107
[INFO] [stdout]    9: rexpect_integration_tests::test_multi_success::{{closure}}
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:83
[INFO] [stdout]   10: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   15: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91
[INFO] [stdout]   16: start_thread
[INFO] [stdout]   17: clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:107:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x555c79229030 - std::backtrace_rs::backtrace::libunwind::trace::h99dbb39dca18857d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5
[INFO] [stdout]    1:     0x555c79229030 - std::backtrace_rs::backtrace::trace_unsynchronized::h832861927e9cfedf
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x555c79229030 - std::sys_common::backtrace::_print_fmt::h3d18154c77dcf310
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x555c79229030 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::he312f4ad5b9bb346
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x555c7924cfbc - core::fmt::write::h9a6d9c74526a6c1b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/fmt/mod.rs:1115:17
[INFO] [stdout]    5:     0x555c79225725 - std::io::Write::write_fmt::h65d36b96410d80d7
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/io/mod.rs:1665:15
[INFO] [stdout]    6:     0x555c7922b65b - std::sys_common::backtrace::_print::h65d996766de40da4
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x555c7922b65b - std::sys_common::backtrace::print::h40df9727e635f303
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x555c7922b65b - std::panicking::default_hook::{{closure}}::hd2da4327dea91a51
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:208:50
[INFO] [stdout]    9:     0x555c7922b16c - std::panicking::default_hook::h3d55120ad6ada158
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:222:9
[INFO] [stdout]   10:     0x555c7922bd24 - std::panicking::rust_panic_with_hook::hf85dd0bb545e3b55
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:622:17
[INFO] [stdout]   11:     0x555c7922b807 - std::panicking::begin_panic_handler::{{closure}}::h736ae969434da9fa
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:519:13
[INFO] [stdout]   12:     0x555c7922950c - std::sys_common::backtrace::__rust_end_short_backtrace::h6133bb80b1d6c3e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:141:18
[INFO] [stdout]   13:     0x555c7922b769 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:515:5
[INFO] [stdout]   14:     0x555c78edc8e1 - core::panicking::panic_fmt::hcf5f6d96e1dd7099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/panicking.rs:92:14
[INFO] [stdout]   15:     0x555c78edc9d3 - core::result::unwrap_failed::he898b02f57993c42
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1599:5
[INFO] [stdout]   16:     0x555c78ee0f03 - core::result::Result<T,E>::unwrap::h9c1365770168b099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1281:23
[INFO] [stdout]   17:     0x555c78ede79b - rexpect_integration_tests::test_multi_success::h49b6098b6b257df7
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:107:5
[INFO] [stdout]   18:     0x555c78ede11a - rexpect_integration_tests::test_multi_success::{{closure}}::h0e9fcb06a8fcfd68
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:83:1
[INFO] [stdout]   19:     0x555c78ee080e - core::ops::function::FnOnce::call_once::hb21d2737ef99325e
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x555c78f15223 - core::ops::function::FnOnce::call_once::h227fa683a5745bd3
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x555c78f15223 - test::__rust_begin_short_backtrace::h3a193bd570ada00a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578:5
[INFO] [stdout]   22:     0x555c78f13c98 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h262d2f411717179a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   23:     0x555c78f13c98 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfec00caec3e52a7b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   24:     0x555c78f13c98 - std::panicking::try::do_call::ha5f42e76ffda569d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   25:     0x555c78f13c98 - std::panicking::try::h53f36df0935b9500
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   26:     0x555c78f13c98 - std::panic::catch_unwind::h5a497f74b0ce006f
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   27:     0x555c78f13c98 - test::run_test_in_process::hab0aead337f48a88
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601:18
[INFO] [stdout]   28:     0x555c78f13c98 - test::run_test::run_test_inner::{{closure}}::h632c383164d4cba2
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493:39
[INFO] [stdout]   29:     0x555c78ee241d - test::run_test::run_test_inner::{{closure}}::h7c4b3612891a4b58
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520:37
[INFO] [stdout]   30:     0x555c78ee241d - std::sys_common::backtrace::__rust_begin_short_backtrace::habf7d8a08419d00e
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125:18
[INFO] [stdout]   31:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h3acba502e2068204
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476:17
[INFO] [stdout]   32:     0x555c78ee6b48 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h526ef819c1964a35
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   33:     0x555c78ee6b48 - std::panicking::try::do_call::hc0b5663d6373cec8
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   34:     0x555c78ee6b48 - std::panicking::try::ha9007ede9d35dcd6
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   35:     0x555c78ee6b48 - std::panic::catch_unwind::hf60d56329ebbb81c
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   36:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::hb1722d498f4ce777
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475:30
[INFO] [stdout]   37:     0x555c78ee6b48 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h15eb01325730c962
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h09f167e08fc945ff
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   39:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf814fe85eeb118e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   40:     0x555c79231787 - std::sys::unix::thread::Thread::new::thread_start::hb71b17934c5f5e68
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91:17
[INFO] [stdout]   41:     0x7fcb0ee1d609 - start_thread
[INFO] [stdout]   42:     0x7fcb0ef85293 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_basic_usage stdout ----
[INFO] [stdout] thread 'test_basic_usage' panicked at 'called `Result::unwrap()` on an `Err` value: Error(Timeout("EOF (End of File)", "`\\r``\\n`\n", 2s), State { next_error: None, backtrace: InternalBacktrace { backtrace: Some(   0: error_chain::backtrace::imp::InternalBacktrace::new
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/backtrace.rs:56
[INFO] [stdout]    1: <error_chain::State as core::default::Default>::default
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/lib.rs:687
[INFO] [stdout]    2: rexpect::errors::Error::from_kind
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:269
[INFO] [stdout]    3: <rexpect::errors::Error as core::convert::From<rexpect::errors::ErrorKind>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/convert/mod.rs:540
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/reader.rs:245
[INFO] [stdout]    6: rexpect::session::StreamSession<W>::exp
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:103
[INFO] [stdout]    7: rexpect::session::StreamSession<W>::exp_eof
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:109
[INFO] [stdout]    8: rexpect_integration_tests::test_basic_usage
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:57
[INFO] [stdout]    9: rexpect_integration_tests::test_basic_usage::{{closure}}
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:39
[INFO] [stdout]   10: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   15: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91
[INFO] [stdout]   16: start_thread
[INFO] [stdout]   17: clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:57:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x555c79229030 - std::backtrace_rs::backtrace::libunwind::trace::h99dbb39dca18857d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5
[INFO] [stdout]    1:     0x555c79229030 - std::backtrace_rs::backtrace::trace_unsynchronized::h832861927e9cfedf
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x555c79229030 - std::sys_common::backtrace::_print_fmt::h3d18154c77dcf310
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x555c79229030 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::he312f4ad5b9bb346
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x555c7924cfbc - core::fmt::write::h9a6d9c74526a6c1b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/fmt/mod.rs:1115:17
[INFO] [stdout]    5:     0x555c79225725 - std::io::Write::write_fmt::h65d36b96410d80d7
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/io/mod.rs:1665:15
[INFO] [stdout]    6:     0x555c7922b65b - std::sys_common::backtrace::_print::h65d996766de40da4
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x555c7922b65b - std::sys_common::backtrace::print::h40df9727e635f303
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x555c7922b65b - std::panicking::default_hook::{{closure}}::hd2da4327dea91a51
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:208:50
[INFO] [stdout]    9:     0x555c7922b16c - std::panicking::default_hook::h3d55120ad6ada158
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:222:9
[INFO] [stdout]   10:     0x555c7922bd24 - std::panicking::rust_panic_with_hook::hf85dd0bb545e3b55
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:622:17
[INFO] [stdout]   11:     0x555c7922b807 - std::panicking::begin_panic_handler::{{closure}}::h736ae969434da9fa
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:519:13
[INFO] [stdout]   12:     0x555c7922950c - std::sys_common::backtrace::__rust_end_short_backtrace::h6133bb80b1d6c3e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:141:18
[INFO] [stdout]   13:     0x555c7922b769 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:515:5
[INFO] [stdout]   14:     0x555c78edc8e1 - core::panicking::panic_fmt::hcf5f6d96e1dd7099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/panicking.rs:92:14
[INFO] [stdout]   15:     0x555c78edc9d3 - core::result::unwrap_failed::he898b02f57993c42
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1599:5
[INFO] [stdout]   16:     0x555c78ee0f03 - core::result::Result<T,E>::unwrap::h9c1365770168b099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1281:23
[INFO] [stdout]   17:     0x555c78edda35 - rexpect_integration_tests::test_basic_usage::hd43e2d819d024e87
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:57:5
[INFO] [stdout]   18:     0x555c78edd4da - rexpect_integration_tests::test_basic_usage::{{closure}}::h32db3fc45b4809f7
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:39:1
[INFO] [stdout]   19:     0x555c78ee088e - core::ops::function::FnOnce::call_once::hddfe7fd106582967
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x555c78f15223 - core::ops::function::FnOnce::call_once::h227fa683a5745bd3
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x555c78f15223 - test::__rust_begin_short_backtrace::h3a193bd570ada00a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578:5
[INFO] [stdout]   22:     0x555c78f13c98 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h262d2f411717179a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   23:     0x555c78f13c98 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfec00caec3e52a7b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   24:     0x555c78f13c98 - std::panicking::try::do_call::ha5f42e76ffda569d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   25:     0x555c78f13c98 - std::panicking::try::h53f36df0935b9500
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   26:     0x555c78f13c98 - std::panic::catch_unwind::h5a497f74b0ce006f
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   27:     0x555c78f13c98 - test::run_test_in_process::hab0aead337f48a88
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601:18
[INFO] [stdout]   28:     0x555c78f13c98 - test::run_test::run_test_inner::{{closure}}::h632c383164d4cba2
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493:39
[INFO] [stdout]   29:     0x555c78ee241d - test::run_test::run_test_inner::{{closure}}::h7c4b3612891a4b58
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520:37
[INFO] [stdout]   30:     0x555c78ee241d - std::sys_common::backtrace::__rust_begin_short_backtrace::habf7d8a08419d00e
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125:18
[INFO] [stdout]   31:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h3acba502e2068204
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476:17
[INFO] [stdout]   32:     0x555c78ee6b48 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h526ef819c1964a35
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   33:     0x555c78ee6b48 - std::panicking::try::do_call::hc0b5663d6373cec8
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   34:     0x555c78ee6b48 - std::panicking::try::ha9007ede9d35dcd6
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   35:     0x555c78ee6b48 - std::panic::catch_unwind::hf60d56329ebbb81c
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   36:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::hb1722d498f4ce777
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475:30
[INFO] [stdout]   37:     0x555c78ee6b48 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h15eb01325730c962
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h09f167e08fc945ff
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   39:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf814fe85eeb118e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   40:     0x555c79231787 - std::sys::unix::thread::Thread::new::thread_start::hb71b17934c5f5e68
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91:17
[INFO] [stdout]   41:     0x7fcb0ee1d609 - start_thread
[INFO] [stdout]   42:     0x7fcb0ef85293 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_basic_rejection stdout ----
[INFO] [stdout] thread 'test_basic_rejection' panicked at 'called `Result::unwrap()` on an `Err` value: Error(Timeout("EOF (End of File)", "`\\r``\\n`\n", 2s), State { next_error: None, backtrace: InternalBacktrace { backtrace: Some(   0: error_chain::backtrace::imp::InternalBacktrace::new
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/backtrace.rs:56
[INFO] [stdout]    1: <error_chain::State as core::default::Default>::default
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/lib.rs:687
[INFO] [stdout]    2: rexpect::errors::Error::from_kind
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:269
[INFO] [stdout]    3: <rexpect::errors::Error as core::convert::From<rexpect::errors::ErrorKind>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/convert/mod.rs:540
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/reader.rs:245
[INFO] [stdout]    6: rexpect::session::StreamSession<W>::exp
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:103
[INFO] [stdout]    7: rexpect::session::StreamSession<W>::exp_eof
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/rexpect-0.4.0/src/session.rs:109
[INFO] [stdout]    8: rexpect_integration_tests::test_basic_rejection
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:79
[INFO] [stdout]    9: rexpect_integration_tests::test_basic_rejection::{{closure}}
[INFO] [stdout]              at tests/rexpect_integration_tests.rs:61
[INFO] [stdout]   10: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227
[INFO] [stdout]   15: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91
[INFO] [stdout]   16: start_thread
[INFO] [stdout]   17: clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:79:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x555c79229030 - std::backtrace_rs::backtrace::libunwind::trace::h99dbb39dca18857d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5
[INFO] [stdout]    1:     0x555c79229030 - std::backtrace_rs::backtrace::trace_unsynchronized::h832861927e9cfedf
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x555c79229030 - std::sys_common::backtrace::_print_fmt::h3d18154c77dcf310
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x555c79229030 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::he312f4ad5b9bb346
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x555c7924cfbc - core::fmt::write::h9a6d9c74526a6c1b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/fmt/mod.rs:1115:17
[INFO] [stdout]    5:     0x555c79225725 - std::io::Write::write_fmt::h65d36b96410d80d7
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/io/mod.rs:1665:15
[INFO] [stdout]    6:     0x555c7922b65b - std::sys_common::backtrace::_print::h65d996766de40da4
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x555c7922b65b - std::sys_common::backtrace::print::h40df9727e635f303
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x555c7922b65b - std::panicking::default_hook::{{closure}}::hd2da4327dea91a51
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:208:50
[INFO] [stdout]    9:     0x555c7922b16c - std::panicking::default_hook::h3d55120ad6ada158
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:222:9
[INFO] [stdout]   10:     0x555c7922bd24 - std::panicking::rust_panic_with_hook::hf85dd0bb545e3b55
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:622:17
[INFO] [stdout]   11:     0x555c7922b807 - std::panicking::begin_panic_handler::{{closure}}::h736ae969434da9fa
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:519:13
[INFO] [stdout]   12:     0x555c7922950c - std::sys_common::backtrace::__rust_end_short_backtrace::h6133bb80b1d6c3e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:141:18
[INFO] [stdout]   13:     0x555c7922b769 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:515:5
[INFO] [stdout]   14:     0x555c78edc8e1 - core::panicking::panic_fmt::hcf5f6d96e1dd7099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/panicking.rs:92:14
[INFO] [stdout]   15:     0x555c78edc9d3 - core::result::unwrap_failed::he898b02f57993c42
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1599:5
[INFO] [stdout]   16:     0x555c78ee0f03 - core::result::Result<T,E>::unwrap::h9c1365770168b099
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/result.rs:1281:23
[INFO] [stdout]   17:     0x555c78ede055 - rexpect_integration_tests::test_basic_rejection::h95765a749791bea8
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:79:5
[INFO] [stdout]   18:     0x555c78eddafa - rexpect_integration_tests::test_basic_rejection::{{closure}}::hf87d14fce2f975c0
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:61:1
[INFO] [stdout]   19:     0x555c78ee065e - core::ops::function::FnOnce::call_once::h4e814b0bc6cd7226
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x555c78f15223 - core::ops::function::FnOnce::call_once::h227fa683a5745bd3
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x555c78f15223 - test::__rust_begin_short_backtrace::h3a193bd570ada00a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:578:5
[INFO] [stdout]   22:     0x555c78f13c98 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h262d2f411717179a
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   23:     0x555c78f13c98 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfec00caec3e52a7b
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   24:     0x555c78f13c98 - std::panicking::try::do_call::ha5f42e76ffda569d
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   25:     0x555c78f13c98 - std::panicking::try::h53f36df0935b9500
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   26:     0x555c78f13c98 - std::panic::catch_unwind::h5a497f74b0ce006f
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   27:     0x555c78f13c98 - test::run_test_in_process::hab0aead337f48a88
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:601:18
[INFO] [stdout]   28:     0x555c78f13c98 - test::run_test::run_test_inner::{{closure}}::h632c383164d4cba2
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:493:39
[INFO] [stdout]   29:     0x555c78ee241d - test::run_test::run_test_inner::{{closure}}::h7c4b3612891a4b58
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/test/src/lib.rs:520:37
[INFO] [stdout]   30:     0x555c78ee241d - std::sys_common::backtrace::__rust_begin_short_backtrace::habf7d8a08419d00e
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys_common/backtrace.rs:125:18
[INFO] [stdout]   31:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h3acba502e2068204
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:476:17
[INFO] [stdout]   32:     0x555c78ee6b48 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h526ef819c1964a35
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:347:9
[INFO] [stdout]   33:     0x555c78ee6b48 - std::panicking::try::do_call::hc0b5663d6373cec8
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:401:40
[INFO] [stdout]   34:     0x555c78ee6b48 - std::panicking::try::ha9007ede9d35dcd6
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panicking.rs:365:19
[INFO] [stdout]   35:     0x555c78ee6b48 - std::panic::catch_unwind::hf60d56329ebbb81c
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/panic.rs:434:14
[INFO] [stdout]   36:     0x555c78ee6b48 - std::thread::Builder::spawn_unchecked::{{closure}}::hb1722d498f4ce777
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/thread/mod.rs:475:30
[INFO] [stdout]   37:     0x555c78ee6b48 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h15eb01325730c962
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h09f167e08fc945ff
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   39:     0x555c79231787 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf814fe85eeb118e0
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/alloc/src/boxed.rs:1572:9
[INFO] [stdout]   40:     0x555c79231787 - std::sys::unix::thread::Thread::new::thread_start::hb71b17934c5f5e68
[INFO] [stdout]                                at /rustc/b70888601af92f6cdc0364abab3446e418b91d36/library/std/src/sys/unix/thread.rs:91:17
[INFO] [stdout]   41:     0x7fcb0ee1d609 - start_thread
[INFO] [stdout]   42:     0x7fcb0ef85293 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_basic_rejection
[INFO] [stdout]     test_basic_usage
[INFO] [stdout]     test_multi_rejection
[INFO] [stdout]     test_multi_success
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 4 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.97s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--test rexpect_integration_tests'
[INFO] running `Command { std: "docker" "inspect" "7d0ca7bfce07d6ebc75907102ab4fef6a09c0d10d2c1be1dfda10c1180f886cf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d0ca7bfce07d6ebc75907102ab4fef6a09c0d10d2c1be1dfda10c1180f886cf", kill_on_drop: false }`
[INFO] [stdout] 7d0ca7bfce07d6ebc75907102ab4fef6a09c0d10d2c1be1dfda10c1180f886cf