[INFO] fetching crate checklist 0.3.0...
[INFO] testing checklist-0.3.0 against master#62efba8a050c64249dab942951bb28f710208bc8 for pr-90799
[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 62efba8a050c64249dab942951bb28f710208bc8
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "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" "+62efba8a050c64249dab942951bb28f710208bc8" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] def8f1d7e22863e62676672f082bc98f8efb5e5d9a33f7a34127492ae793b8b0
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "def8f1d7e22863e62676672f082bc98f8efb5e5d9a33f7a34127492ae793b8b0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "def8f1d7e22863e62676672f082bc98f8efb5e5d9a33f7a34127492ae793b8b0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "def8f1d7e22863e62676672f082bc98f8efb5e5d9a33f7a34127492ae793b8b0", kill_on_drop: false }`
[INFO] [stdout] def8f1d7e22863e62676672f082bc98f8efb5e5d9a33f7a34127492ae793b8b0
[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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 9c6d21599630ccb204f670dfe617ab3001b3f52d2a46d55ca4dc3e5828c4d365
[INFO] running `Command { std: "docker" "start" "-a" "9c6d21599630ccb204f670dfe617ab3001b3f52d2a46d55ca4dc3e5828c4d365", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.72
[INFO] [stderr]    Compiling syn v1.0.33
[INFO] [stderr]    Compiling adler v0.2.2
[INFO] [stderr]    Compiling serde v1.0.114
[INFO] [stderr]    Compiling number_prefix v0.3.0
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.3
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling proc-macro-error v1.0.3
[INFO] [stderr]    Compiling yaml-rust v0.4.4
[INFO] [stderr]    Compiling proc-macro2 v1.0.18
[INFO] [stderr]    Compiling miniz_oxide v0.4.0
[INFO] [stderr]    Compiling regex v1.3.9
[INFO] [stderr]    Compiling quote v1.0.7
[INFO] [stderr]    Compiling getrandom v0.1.14
[INFO] [stderr]    Compiling terminal_size v0.1.13
[INFO] [stderr]    Compiling termios v0.3.2
[INFO] [stderr]    Compiling shared_child v0.3.4
[INFO] [stderr]    Compiling os_pipe v0.9.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling backtrace v0.3.50
[INFO] [stderr]    Compiling clap v2.33.1
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling duct v0.13.4
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling duct_sh v0.13.4
[INFO] [stderr]    Compiling console v0.11.3
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling indicatif v0.15.0
[INFO] [stderr]    Compiling tempfile v3.1.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_derive v0.1.8
[INFO] [stderr]    Compiling serde_derive v1.0.114
[INFO] [stderr]    Compiling structopt-derive v0.4.8
[INFO] [stderr]    Compiling failure v0.1.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 1m 17s
[INFO] running `Command { std: "docker" "inspect" "9c6d21599630ccb204f670dfe617ab3001b3f52d2a46d55ca4dc3e5828c4d365", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9c6d21599630ccb204f670dfe617ab3001b3f52d2a46d55ca4dc3e5828c4d365", kill_on_drop: false }`
[INFO] [stdout] 9c6d21599630ccb204f670dfe617ab3001b3f52d2a46d55ca4dc3e5828c4d365
[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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 97dc979b3353a10495ae07ecb8f8bbaee732e8beff7354b29724e763ac172215
[INFO] running `Command { std: "docker" "start" "-a" "97dc979b3353a10495ae07ecb8f8bbaee732e8beff7354b29724e763ac172215", kill_on_drop: false }`
[INFO] [stderr]    Compiling predicates-core v1.0.0
[INFO] [stderr]    Compiling doc-comment v0.3.3
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling nix v0.14.1
[INFO] [stderr]    Compiling difference v2.0.0
[INFO] [stderr]    Compiling treeline v0.1.0
[INFO] [stderr]    Compiling void v1.0.2
[INFO] [stderr]    Compiling error-chain v0.12.2
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling walkdir v2.3.1
[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 crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling predicates-tree v1.0.0
[INFO] [stderr]    Compiling globset v0.4.5
[INFO] [stderr]    Compiling float-cmp v0.6.0
[INFO] [stderr]    Compiling predicates v1.0.4
[INFO] [stderr]    Compiling ignore v0.4.16
[INFO] [stderr]    Compiling assert_cmd v1.0.1
[INFO] [stderr]    Compiling globwalk v0.7.3
[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 31.81s
[INFO] running `Command { std: "docker" "inspect" "97dc979b3353a10495ae07ecb8f8bbaee732e8beff7354b29724e763ac172215", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "97dc979b3353a10495ae07ecb8f8bbaee732e8beff7354b29724e763ac172215", kill_on_drop: false }`
[INFO] [stdout] 97dc979b3353a10495ae07ecb8f8bbaee732e8beff7354b29724e763ac172215
[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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+62efba8a050c64249dab942951bb28f710208bc8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 4f3686c0c16bbea14b7bb04be20958179a6ebd7feaf61f95475fb61633d1953f
[INFO] running `Command { std: "docker" "start" "-a" "4f3686c0c16bbea14b7bb04be20958179a6ebd7feaf61f95475fb61633d1953f", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/checklist-5aa52e17f01c6951)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test tests::test_correct_yaml ... ok
[INFO] [stdout] test tests::test_defaults ... ok
[INFO] [stdout] test tests::test_incorrect_schema_version ... ok
[INFO] [stdout] test tests::test_invalid_filename ... ok
[INFO] [stdout] test tests::test_return_code_fail ... ok
[INFO] [stdout] test tests::test_incorrect_yaml ... ok
[INFO] [stdout] test tests::test_return_code ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/checklist-757c29cad1c1580c)
[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-4026d6833d857075)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_multi_success ... FAILED
[INFO] [stdout] test test_multi_rejection ... FAILED
[INFO] [stdout] test test_basic_rejection ... FAILED
[INFO] [stdout] test test_basic_usage ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/convert/mod.rs:542
[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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/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/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106
[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:     0x55a5678d566c - std::backtrace_rs::backtrace::libunwind::trace::h793e05efd273d0f4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x55a5678d566c - std::backtrace_rs::backtrace::trace_unsynchronized::h640b7b86ff610c77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55a5678d566c - std::sys_common::backtrace::_print_fmt::h362fa2a4f354f877
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x55a5678d566c - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf439e5ed84c74abd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x55a5678f9a3c - core::fmt::write::h72801a82c94e6ff1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/fmt/mod.rs:1149:17
[INFO] [stdout]    5:     0x55a5678d0e85 - std::io::Write::write_fmt::h54337adeb3504c03
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/io/mod.rs:1697:15
[INFO] [stdout]    6:     0x55a5678d74e0 - std::sys_common::backtrace::_print::hb29ddd998d02631c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x55a5678d74e0 - std::sys_common::backtrace::print::h81965e3d7c90fbb6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x55a5678d74e0 - std::panicking::default_hook::{{closure}}::h84db205ab6674b38
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:211:50
[INFO] [stdout]    9:     0x55a5678d70c6 - std::panicking::default_hook::h1bf8bb4159936bca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:225:9
[INFO] [stdout]   10:     0x55a5678d7b94 - std::panicking::rust_panic_with_hook::hf8e86850fbbd03b1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:606:17
[INFO] [stdout]   11:     0x55a5678d7670 - std::panicking::begin_panic_handler::{{closure}}::h590a0d6060ff866e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:502:13
[INFO] [stdout]   12:     0x55a5678d5b24 - std::sys_common::backtrace::__rust_end_short_backtrace::h260b8bd1c848a03c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:139:18
[INFO] [stdout]   13:     0x55a5678d75d9 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:498:5
[INFO] [stdout]   14:     0x55a567585da1 - core::panicking::panic_fmt::h7b8580d81fcbbacd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panicking.rs:106:14
[INFO] [stdout]   15:     0x55a567585e93 - core::result::unwrap_failed::h885d3f7beb571353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1613:5
[INFO] [stdout]   16:     0x55a56758a282 - core::result::Result<T,E>::unwrap::h987d36aabd2877b6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1295:23
[INFO] [stdout]   17:     0x55a567587b68 - rexpect_integration_tests::test_multi_success::h1221c85393d7d434
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:107:5
[INFO] [stdout]   18:     0x55a56758751a - rexpect_integration_tests::test_multi_success::{{closure}}::h646a9ed4819d1b0b
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:83:1
[INFO] [stdout]   19:     0x55a567589bbe - core::ops::function::FnOnce::call_once::h62a1aad734308325
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x55a5675bf783 - core::ops::function::FnOnce::call_once::h24a32b9da1a40fdb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x55a5675bf783 - test::__rust_begin_short_backtrace::h3c3a0492fb4a12eb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585:5
[INFO] [stdout]   22:     0x55a5675be397 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h9a8e38435f73d5e0
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   23:     0x55a5675be397 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb23d7496a0e6d1b4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   24:     0x55a5675be397 - std::panicking::try::do_call::ha2bb9825b32d2cc2
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   25:     0x55a5675be397 - std::panicking::try::hdc38a574bbebc53e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   26:     0x55a5675be397 - std::panic::catch_unwind::hc45ed246595c621c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   27:     0x55a5675be397 - test::run_test_in_process::h4249138faaea8d54
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608:18
[INFO] [stdout]   28:     0x55a5675be397 - test::run_test::run_test_inner::{{closure}}::h3c1b37669f6e9fd7
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500:39
[INFO] [stdout]   29:     0x55a56758b63e - test::run_test::run_test_inner::{{closure}}::h388760469edd5c24
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527:37
[INFO] [stdout]   30:     0x55a56758b63e - std::sys_common::backtrace::__rust_begin_short_backtrace::hbbaecdcfad18643e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123:18
[INFO] [stdout]   31:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h09cd3ca65896da91
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484:17
[INFO] [stdout]   32:     0x55a567590398 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc52dbb4f9088d18b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   33:     0x55a567590398 - std::panicking::try::do_call::h4485cfee22b11c08
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   34:     0x55a567590398 - std::panicking::try::hb62aa8549e20c08b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   35:     0x55a567590398 - std::panic::catch_unwind::ha322c7a5bc666762
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   36:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::h1076fc1398603b77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483:30
[INFO] [stdout]   37:     0x55a567590398 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h911e510344167353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h771719d52c343434
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   39:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf441746dfa4b0f57
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   40:     0x55a5678dd543 - std::sys::unix::thread::Thread::new::thread_start::hfd168f9d312b29ca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106:17
[INFO] [stdout]   41:     0x7f814e043609 - start_thread
[INFO] [stdout]   42:     0x7f814e1ab293 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/convert/mod.rs:542
[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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/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/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106
[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:     0x55a5678d566c - std::backtrace_rs::backtrace::libunwind::trace::h793e05efd273d0f4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x55a5678d566c - std::backtrace_rs::backtrace::trace_unsynchronized::h640b7b86ff610c77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55a5678d566c - std::sys_common::backtrace::_print_fmt::h362fa2a4f354f877
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x55a5678d566c - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf439e5ed84c74abd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x55a5678f9a3c - core::fmt::write::h72801a82c94e6ff1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/fmt/mod.rs:1149:17
[INFO] [stdout]    5:     0x55a5678d0e85 - std::io::Write::write_fmt::h54337adeb3504c03
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/io/mod.rs:1697:15
[INFO] [stdout]    6:     0x55a5678d74e0 - std::sys_common::backtrace::_print::hb29ddd998d02631c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x55a5678d74e0 - std::sys_common::backtrace::print::h81965e3d7c90fbb6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x55a5678d74e0 - std::panicking::default_hook::{{closure}}::h84db205ab6674b38
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:211:50
[INFO] [stdout]    9:     0x55a5678d70c6 - std::panicking::default_hook::h1bf8bb4159936bca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:225:9
[INFO] [stdout]   10:     0x55a5678d7b94 - std::panicking::rust_panic_with_hook::hf8e86850fbbd03b1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:606:17
[INFO] [stdout]   11:     0x55a5678d7670 - std::panicking::begin_panic_handler::{{closure}}::h590a0d6060ff866e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:502:13
[INFO] [stdout]   12:     0x55a5678d5b24 - std::sys_common::backtrace::__rust_end_short_backtrace::h260b8bd1c848a03c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:139:18
[INFO] [stdout]   13:     0x55a5678d75d9 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:498:5
[INFO] [stdout]   14:     0x55a567585da1 - core::panicking::panic_fmt::h7b8580d81fcbbacd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panicking.rs:106:14
[INFO] [stdout]   15:     0x55a567585e93 - core::result::unwrap_failed::h885d3f7beb571353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1613:5
[INFO] [stdout]   16:     0x55a56758a282 - core::result::Result<T,E>::unwrap::h987d36aabd2877b6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1295:23
[INFO] [stdout]   17:     0x55a5675882e3 - rexpect_integration_tests::test_multi_rejection::hfce891b399696320
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:136:5
[INFO] [stdout]   18:     0x55a567587c2a - rexpect_integration_tests::test_multi_rejection::{{closure}}::hcecac1aaf594ffcd
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:111:1
[INFO] [stdout]   19:     0x55a567589b1e - core::ops::function::FnOnce::call_once::h19ede85c6f83ef28
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x55a5675bf783 - core::ops::function::FnOnce::call_once::h24a32b9da1a40fdb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x55a5675bf783 - test::__rust_begin_short_backtrace::h3c3a0492fb4a12eb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585:5
[INFO] [stdout]   22:     0x55a5675be397 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h9a8e38435f73d5e0
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   23:     0x55a5675be397 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb23d7496a0e6d1b4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   24:     0x55a5675be397 - std::panicking::try::do_call::ha2bb9825b32d2cc2
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   25:     0x55a5675be397 - std::panicking::try::hdc38a574bbebc53e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   26:     0x55a5675be397 - std::panic::catch_unwind::hc45ed246595c621c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   27:     0x55a5675be397 - test::run_test_in_process::h4249138faaea8d54
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608:18
[INFO] [stdout]   28:     0x55a5675be397 - test::run_test::run_test_inner::{{closure}}::h3c1b37669f6e9fd7
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500:39
[INFO] [stdout]   29:     0x55a56758b63e - test::run_test::run_test_inner::{{closure}}::h388760469edd5c24
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527:37
[INFO] [stdout]   30:     0x55a56758b63e - std::sys_common::backtrace::__rust_begin_short_backtrace::hbbaecdcfad18643e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123:18
[INFO] [stdout]   31:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h09cd3ca65896da91
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484:17
[INFO] [stdout]   32:     0x55a567590398 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc52dbb4f9088d18b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   33:     0x55a567590398 - std::panicking::try::do_call::h4485cfee22b11c08
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   34:     0x55a567590398 - std::panicking::try::hb62aa8549e20c08b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   35:     0x55a567590398 - std::panic::catch_unwind::ha322c7a5bc666762
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   36:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::h1076fc1398603b77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483:30
[INFO] [stdout]   37:     0x55a567590398 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h911e510344167353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h771719d52c343434
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   39:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf441746dfa4b0f57
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   40:     0x55a5678dd543 - std::sys::unix::thread::Thread::new::thread_start::hfd168f9d312b29ca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106:17
[INFO] [stdout]   41:     0x7f814e043609 - start_thread
[INFO] [stdout]   42:     0x7f814e1ab293 - 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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/convert/mod.rs:542
[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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/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/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106
[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:     0x55a5678d566c - std::backtrace_rs::backtrace::libunwind::trace::h793e05efd273d0f4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x55a5678d566c - std::backtrace_rs::backtrace::trace_unsynchronized::h640b7b86ff610c77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55a5678d566c - std::sys_common::backtrace::_print_fmt::h362fa2a4f354f877
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x55a5678d566c - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf439e5ed84c74abd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x55a5678f9a3c - core::fmt::write::h72801a82c94e6ff1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/fmt/mod.rs:1149:17
[INFO] [stdout]    5:     0x55a5678d0e85 - std::io::Write::write_fmt::h54337adeb3504c03
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/io/mod.rs:1697:15
[INFO] [stdout]    6:     0x55a5678d74e0 - std::sys_common::backtrace::_print::hb29ddd998d02631c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x55a5678d74e0 - std::sys_common::backtrace::print::h81965e3d7c90fbb6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x55a5678d74e0 - std::panicking::default_hook::{{closure}}::h84db205ab6674b38
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:211:50
[INFO] [stdout]    9:     0x55a5678d70c6 - std::panicking::default_hook::h1bf8bb4159936bca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:225:9
[INFO] [stdout]   10:     0x55a5678d7b94 - std::panicking::rust_panic_with_hook::hf8e86850fbbd03b1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:606:17
[INFO] [stdout]   11:     0x55a5678d7670 - std::panicking::begin_panic_handler::{{closure}}::h590a0d6060ff866e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:502:13
[INFO] [stdout]   12:     0x55a5678d5b24 - std::sys_common::backtrace::__rust_end_short_backtrace::h260b8bd1c848a03c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:139:18
[INFO] [stdout]   13:     0x55a5678d75d9 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:498:5
[INFO] [stdout]   14:     0x55a567585da1 - core::panicking::panic_fmt::h7b8580d81fcbbacd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panicking.rs:106:14
[INFO] [stdout]   15:     0x55a567585e93 - core::result::unwrap_failed::h885d3f7beb571353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1613:5
[INFO] [stdout]   16:     0x55a56758a282 - core::result::Result<T,E>::unwrap::h987d36aabd2877b6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1295:23
[INFO] [stdout]   17:     0x55a56758745d - rexpect_integration_tests::test_basic_rejection::he423f6fa9cbcf84e
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:79:5
[INFO] [stdout]   18:     0x55a567586f2a - rexpect_integration_tests::test_basic_rejection::{{closure}}::h28183bc043724339
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:61:1
[INFO] [stdout]   19:     0x55a567589c4e - core::ops::function::FnOnce::call_once::hc0528637015cea81
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x55a5675bf783 - core::ops::function::FnOnce::call_once::h24a32b9da1a40fdb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x55a5675bf783 - test::__rust_begin_short_backtrace::h3c3a0492fb4a12eb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585:5
[INFO] [stdout]   22:     0x55a5675be397 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h9a8e38435f73d5e0
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   23:     0x55a5675be397 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb23d7496a0e6d1b4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   24:     0x55a5675be397 - std::panicking::try::do_call::ha2bb9825b32d2cc2
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   25:     0x55a5675be397 - std::panicking::try::hdc38a574bbebc53e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   26:     0x55a5675be397 - std::panic::catch_unwind::hc45ed246595c621c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   27:     0x55a5675be397 - test::run_test_in_process::h4249138faaea8d54
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608:18
[INFO] [stdout]   28:     0x55a5675be397 - test::run_test::run_test_inner::{{closure}}::h3c1b37669f6e9fd7
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500:39
[INFO] [stdout]   29:     0x55a56758b63e - test::run_test::run_test_inner::{{closure}}::h388760469edd5c24
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527:37
[INFO] [stdout]   30:     0x55a56758b63e - std::sys_common::backtrace::__rust_begin_short_backtrace::hbbaecdcfad18643e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123:18
[INFO] [stdout]   31:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h09cd3ca65896da91
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484:17
[INFO] [stdout]   32:     0x55a567590398 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc52dbb4f9088d18b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   33:     0x55a567590398 - std::panicking::try::do_call::h4485cfee22b11c08
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   34:     0x55a567590398 - std::panicking::try::hb62aa8549e20c08b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   35:     0x55a567590398 - std::panic::catch_unwind::ha322c7a5bc666762
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   36:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::h1076fc1398603b77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483:30
[INFO] [stdout]   37:     0x55a567590398 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h911e510344167353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h771719d52c343434
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   39:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf441746dfa4b0f57
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   40:     0x55a5678dd543 - std::sys::unix::thread::Thread::new::thread_start::hfd168f9d312b29ca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106:17
[INFO] [stdout]   41:     0x7f814e043609 - start_thread
[INFO] [stdout]   42:     0x7f814e1ab293 - 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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/convert/mod.rs:542
[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/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585
[INFO] [stdout]   12: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500
[INFO] [stdout]   13: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123
[INFO] [stdout]   14: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/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/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106
[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:     0x55a5678d566c - std::backtrace_rs::backtrace::libunwind::trace::h793e05efd273d0f4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x55a5678d566c - std::backtrace_rs::backtrace::trace_unsynchronized::h640b7b86ff610c77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55a5678d566c - std::sys_common::backtrace::_print_fmt::h362fa2a4f354f877
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x55a5678d566c - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf439e5ed84c74abd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x55a5678f9a3c - core::fmt::write::h72801a82c94e6ff1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/fmt/mod.rs:1149:17
[INFO] [stdout]    5:     0x55a5678d0e85 - std::io::Write::write_fmt::h54337adeb3504c03
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/io/mod.rs:1697:15
[INFO] [stdout]    6:     0x55a5678d74e0 - std::sys_common::backtrace::_print::hb29ddd998d02631c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x55a5678d74e0 - std::sys_common::backtrace::print::h81965e3d7c90fbb6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x55a5678d74e0 - std::panicking::default_hook::{{closure}}::h84db205ab6674b38
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:211:50
[INFO] [stdout]    9:     0x55a5678d70c6 - std::panicking::default_hook::h1bf8bb4159936bca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:225:9
[INFO] [stdout]   10:     0x55a5678d7b94 - std::panicking::rust_panic_with_hook::hf8e86850fbbd03b1
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:606:17
[INFO] [stdout]   11:     0x55a5678d7670 - std::panicking::begin_panic_handler::{{closure}}::h590a0d6060ff866e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:502:13
[INFO] [stdout]   12:     0x55a5678d5b24 - std::sys_common::backtrace::__rust_end_short_backtrace::h260b8bd1c848a03c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:139:18
[INFO] [stdout]   13:     0x55a5678d75d9 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:498:5
[INFO] [stdout]   14:     0x55a567585da1 - core::panicking::panic_fmt::h7b8580d81fcbbacd
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panicking.rs:106:14
[INFO] [stdout]   15:     0x55a567585e93 - core::result::unwrap_failed::h885d3f7beb571353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1613:5
[INFO] [stdout]   16:     0x55a56758a282 - core::result::Result<T,E>::unwrap::h987d36aabd2877b6
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/result.rs:1295:23
[INFO] [stdout]   17:     0x55a567586e6d - rexpect_integration_tests::test_basic_usage::hbc7c5c53b41a1e1a
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:57:5
[INFO] [stdout]   18:     0x55a56758693a - rexpect_integration_tests::test_basic_usage::{{closure}}::hc6b50671aa22fc7e
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:39:1
[INFO] [stdout]   19:     0x55a567589dde - core::ops::function::FnOnce::call_once::hd5abf78906f4123f
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x55a5675bf783 - core::ops::function::FnOnce::call_once::h24a32b9da1a40fdb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x55a5675bf783 - test::__rust_begin_short_backtrace::h3c3a0492fb4a12eb
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:585:5
[INFO] [stdout]   22:     0x55a5675be397 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h9a8e38435f73d5e0
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   23:     0x55a5675be397 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb23d7496a0e6d1b4
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   24:     0x55a5675be397 - std::panicking::try::do_call::ha2bb9825b32d2cc2
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   25:     0x55a5675be397 - std::panicking::try::hdc38a574bbebc53e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   26:     0x55a5675be397 - std::panic::catch_unwind::hc45ed246595c621c
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   27:     0x55a5675be397 - test::run_test_in_process::h4249138faaea8d54
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:608:18
[INFO] [stdout]   28:     0x55a5675be397 - test::run_test::run_test_inner::{{closure}}::h3c1b37669f6e9fd7
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:500:39
[INFO] [stdout]   29:     0x55a56758b63e - test::run_test::run_test_inner::{{closure}}::h388760469edd5c24
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/test/src/lib.rs:527:37
[INFO] [stdout]   30:     0x55a56758b63e - std::sys_common::backtrace::__rust_begin_short_backtrace::hbbaecdcfad18643e
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys_common/backtrace.rs:123:18
[INFO] [stdout]   31:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h09cd3ca65896da91
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:484:17
[INFO] [stdout]   32:     0x55a567590398 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc52dbb4f9088d18b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   33:     0x55a567590398 - std::panicking::try::do_call::h4485cfee22b11c08
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:406:40
[INFO] [stdout]   34:     0x55a567590398 - std::panicking::try::hb62aa8549e20c08b
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panicking.rs:370:19
[INFO] [stdout]   35:     0x55a567590398 - std::panic::catch_unwind::ha322c7a5bc666762
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/panic.rs:133:14
[INFO] [stdout]   36:     0x55a567590398 - std::thread::Builder::spawn_unchecked::{{closure}}::h1076fc1398603b77
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/thread/mod.rs:483:30
[INFO] [stdout]   37:     0x55a567590398 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h911e510344167353
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h771719d52c343434
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   39:     0x55a5678dd543 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf441746dfa4b0f57
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/alloc/src/boxed.rs:1694:9
[INFO] [stdout]   40:     0x55a5678dd543 - std::sys::unix::thread::Thread::new::thread_start::hfd168f9d312b29ca
[INFO] [stdout]                                at /rustc/62efba8a050c64249dab942951bb28f710208bc8/library/std/src/sys/unix/thread.rs:106:17
[INFO] [stdout]   41:     0x7f814e043609 - start_thread
[INFO] [stdout]   42:     0x7f814e1ab293 - 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 4.85s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--test rexpect_integration_tests'
[INFO] running `Command { std: "docker" "inspect" "4f3686c0c16bbea14b7bb04be20958179a6ebd7feaf61f95475fb61633d1953f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4f3686c0c16bbea14b7bb04be20958179a6ebd7feaf61f95475fb61633d1953f", kill_on_drop: false }`
[INFO] [stdout] 4f3686c0c16bbea14b7bb04be20958179a6ebd7feaf61f95475fb61633d1953f