[INFO] fetching crate checklist 0.3.0...
[INFO] testing checklist-0.3.0 against beta-2023-04-20 for beta-1.70-1
[INFO] extracting crate checklist 0.3.0 into /workspace/builds/worker-6-tc2/source
[INFO] validating manifest of crates.io crate checklist 0.3.0 on toolchain beta-2023-04-20
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-04-20" "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-6-tc2/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: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-04-20" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded termios v0.3.2
[INFO] [stderr]   Downloaded shared_child v0.3.4
[INFO] [stderr]   Downloaded terminal_size v0.1.13
[INFO] [stderr]   Downloaded os_pipe v0.9.2
[INFO] [stderr]   Downloaded predicates-tree v1.0.0
[INFO] [stderr]   Downloaded proc-macro2 v1.0.18
[INFO] [stderr]   Downloaded syn v1.0.33
[INFO] [stderr]   Downloaded console v0.11.3
[INFO] [stderr]   Downloaded serde v1.0.114
[INFO] [stderr]   Downloaded serde_yaml v0.8.13
[INFO] [stderr]   Downloaded yaml-rust v0.4.4
[INFO] [stderr]   Downloaded syn-mid v0.5.0
[INFO] [stderr]   Downloaded serde_derive v1.0.114
[INFO] [stderr]   Downloaded error-chain v0.12.2
[INFO] [stderr]   Downloaded dtoa v0.4.6
[INFO] [stderr]   Downloaded object v0.20.0
[INFO] [stderr]   Downloaded cc v1.0.58
[INFO] [stderr]   Downloaded adler v0.2.2
[INFO] [stderr]   Downloaded miniz_oxide v0.4.0
[INFO] [stderr]   Downloaded rexpect v0.4.0
[INFO] [stderr]   Downloaded backtrace v0.3.50
[INFO] [stderr]   Downloaded indicatif v0.15.0
[INFO] [stderr]   Downloaded dialoguer v0.6.2
[INFO] [stderr]   Downloaded float-cmp v0.6.0
[INFO] [stderr]   Downloaded predicates-core v1.0.0
[INFO] [stderr]   Downloaded addr2line v0.13.0
[INFO] [stderr]   Downloaded globset v0.4.5
[INFO] [stderr]   Downloaded assert_cmd v1.0.1
[INFO] [stderr]   Downloaded globwalk v0.7.3
[INFO] [stderr]   Downloaded predicates v1.0.4
[INFO] [stderr]   Downloaded number_prefix v0.3.0
[INFO] [stderr]   Downloaded libc v0.2.72
[INFO] [stderr]   Downloaded aho-corasick v0.7.13
[INFO] [stderr]   Downloaded proc-macro-error-attr v1.0.3
[INFO] [stderr]   Downloaded proc-macro-error v1.0.3
[INFO] [stderr]   Downloaded structopt-derive v0.4.8
[INFO] [stderr]   Downloaded nix v0.14.1
[INFO] [stderr]   Downloaded structopt v0.3.15
[INFO] [stderr]   Downloaded duct v0.13.4
[INFO] [stderr]   Downloaded ignore v0.4.16
[INFO] [stderr]   Downloaded bstr v0.2.13
[INFO] [stderr]   Downloaded duct_sh v0.13.4
[INFO] [stderr]   Downloaded gimli v0.22.0
[INFO] [stderr]   Downloaded assert_fs v1.0.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-04-20" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a8c77004b35a92cb2005fd15e6b10e940e222ef8d8fa92cef1e6c94f69d35f93
[INFO] running `Command { std: "docker" "start" "-a" "a8c77004b35a92cb2005fd15e6b10e940e222ef8d8fa92cef1e6c94f69d35f93", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a8c77004b35a92cb2005fd15e6b10e940e222ef8d8fa92cef1e6c94f69d35f93", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a8c77004b35a92cb2005fd15e6b10e940e222ef8d8fa92cef1e6c94f69d35f93", kill_on_drop: false }`
[INFO] [stdout] a8c77004b35a92cb2005fd15e6b10e940e222ef8d8fa92cef1e6c94f69d35f93
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-04-20" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9c8890aabcd306a81bddd762e3845f40f351c8a350af84ab8035f495857664c5
[INFO] running `Command { std: "docker" "start" "-a" "9c8890aabcd306a81bddd762e3845f40f351c8a350af84ab8035f495857664c5", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.72
[INFO] [stderr]    Compiling proc-macro2 v1.0.18
[INFO] [stderr]    Compiling unicode-xid v0.2.1
[INFO] [stderr]    Compiling syn v1.0.33
[INFO] [stderr]    Compiling version_check v0.9.2
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling memchr v2.3.3
[INFO] [stderr]    Compiling getrandom v0.1.14
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling unicode-width v0.1.8
[INFO] [stderr]    Compiling ppv-lite86 v0.2.8
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling regex-syntax v0.6.18
[INFO] [stderr]    Compiling failure_derive v0.1.8
[INFO] [stderr]    Compiling unicode-segmentation v1.6.0
[INFO] [stderr]    Compiling gimli v0.22.0
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling serde v1.0.114
[INFO] [stderr]    Compiling adler v0.2.2
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling miniz_oxide v0.4.0
[INFO] [stderr]    Compiling object v0.20.0
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.3
[INFO] [stderr]    Compiling proc-macro-error v1.0.3
[INFO] [stderr]    Compiling linked-hash-map v0.5.3
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling serde_derive v1.0.114
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling once_cell v1.4.0
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling aho-corasick v0.7.13
[INFO] [stderr]    Compiling rustc-demangle v0.1.16
[INFO] [stderr]    Compiling yaml-rust v0.4.4
[INFO] [stderr]    Compiling quote v1.0.7
[INFO] [stderr]    Compiling dtoa v0.4.6
[INFO] [stderr]    Compiling number_prefix v0.3.0
[INFO] [stderr]    Compiling termios v0.3.2
[INFO] [stderr]    Compiling terminal_size v0.1.13
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling shared_child v0.3.4
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling os_pipe v0.9.2
[INFO] [stderr]    Compiling clap v2.33.1
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling duct v0.13.4
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling duct_sh v0.13.4
[INFO] [stderr]    Compiling addr2line v0.13.0
[INFO] [stderr]    Compiling regex v1.3.9
[INFO] [stderr]    Compiling backtrace v0.3.50
[INFO] [stderr]    Compiling tempfile v3.1.0
[INFO] [stderr]    Compiling console v0.11.3
[INFO] [stderr]    Compiling dialoguer v0.6.2
[INFO] [stderr]    Compiling indicatif v0.15.0
[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 12.75s
[INFO] running `Command { std: "docker" "inspect" "9c8890aabcd306a81bddd762e3845f40f351c8a350af84ab8035f495857664c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9c8890aabcd306a81bddd762e3845f40f351c8a350af84ab8035f495857664c5", kill_on_drop: false }`
[INFO] [stdout] 9c8890aabcd306a81bddd762e3845f40f351c8a350af84ab8035f495857664c5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-04-20" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 808efc238aa0f408ac81c032854fc986a8d8b7488b4122eb3ee61b63720d7b85
[INFO] running `Command { std: "docker" "start" "-a" "808efc238aa0f408ac81c032854fc986a8d8b7488b4122eb3ee61b63720d7b85", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v1.0.0
[INFO] [stderr]    Compiling log v0.4.8
[INFO] [stderr]    Compiling bstr v0.2.13
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling doc-comment v0.3.3
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling predicates-core v1.0.0
[INFO] [stderr]    Compiling error-chain v0.12.2
[INFO] [stderr]    Compiling treeline v0.1.0
[INFO] [stderr]    Compiling difference v2.0.0
[INFO] [stderr]    Compiling nix v0.14.1
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling void v1.0.2
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling walkdir v2.3.1
[INFO] [stderr]    Compiling predicates-tree v1.0.0
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[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 7.59s
[INFO] running `Command { std: "docker" "inspect" "808efc238aa0f408ac81c032854fc986a8d8b7488b4122eb3ee61b63720d7b85", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "808efc238aa0f408ac81c032854fc986a8d8b7488b4122eb3ee61b63720d7b85", kill_on_drop: false }`
[INFO] [stdout] 808efc238aa0f408ac81c032854fc986a8d8b7488b4122eb3ee61b63720d7b85
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-04-20" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 899975e63e9d5d53a5adf41af99de9f7f1cd3a909ead6b3fae9c23fd625e1ab8
[INFO] running `Command { std: "docker" "start" "-a" "899975e63e9d5d53a5adf41af99de9f7f1cd3a909ead6b3fae9c23fd625e1ab8", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/checklist-4cc7b85069021c73)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test tests::test_defaults ... ok
[INFO] [stdout] test tests::test_correct_yaml ... ok
[INFO] [stdout] test tests::test_incorrect_yaml ... 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_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 src/main.rs (/opt/rustwide/target/debug/deps/checklist-2754e2b0950335c5)
[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-c9e371bb700ef0db)
[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_rejection ... FAILED
[INFO] [stdout] test test_basic_usage ... 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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/convert/mod.rs:727
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655
[INFO] [stdout]   12: test::run_test::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   13: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572
[INFO] [stdout]   14: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134
[INFO] [stdout]   15: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108
[INFO] [stdout]   17: <unknown>
[INFO] [stdout]   18: __clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:136:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5606ba11e14a - std::backtrace_rs::backtrace::libunwind::trace::h2f05d0192276a8f6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x5606ba11e14a - std::backtrace_rs::backtrace::trace_unsynchronized::hedc23dff7a1af150
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5606ba11e14a - std::sys_common::backtrace::_print_fmt::h17541ef7592f3ce2
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:65:5
[INFO] [stdout]    3:     0x5606ba11e14a - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h20f75df5ab0c0792
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x5606ba1434ef - core::fmt::write::h78109e6f466b3af6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/fmt/mod.rs:1254:17
[INFO] [stdout]    5:     0x5606ba11ad75 - std::io::Write::write_fmt::hd24bfb68a1b35a25
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/io/mod.rs:1698:15
[INFO] [stdout]    6:     0x5606ba11df15 - std::sys_common::backtrace::_print::h8dac57a9f8fbf9a5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    7:     0x5606ba11df15 - std::sys_common::backtrace::print::hf9ecbf41d1aa712f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    8:     0x5606ba11fc4e - std::panicking::default_hook::{{closure}}::h04c07acfc84ca028
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:269:22
[INFO] [stdout]    9:     0x5606ba11f981 - std::panicking::default_hook::h01f6bb23e1eceb2c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:285:9
[INFO] [stdout]   10:     0x5606b9e6083b - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h87fe9f7b2fd49e4c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   11:     0x5606b9e6083b - test::test_main::{{closure}}::he94d679243a7531b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:134:21
[INFO] [stdout]   12:     0x5606ba120334 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h3f2d6d8b14c4769f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   13:     0x5606ba120334 - std::panicking::rust_panic_with_hook::h2bb3722bbaf2f6df
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:695:13
[INFO] [stdout]   14:     0x5606ba1200a9 - std::panicking::begin_panic_handler::{{closure}}::h91bc7f36a4be011b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:582:13
[INFO] [stdout]   15:     0x5606ba11e5b6 - std::sys_common::backtrace::__rust_end_short_backtrace::h8b3d3717114f9e59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:150:18
[INFO] [stdout]   16:     0x5606ba11fe02 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:578:5
[INFO] [stdout]   17:     0x5606b9e28413 - core::panicking::panic_fmt::hfd368e3fbdabea26
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panicking.rs:67:14
[INFO] [stdout]   18:     0x5606b9e289b3 - core::result::unwrap_failed::he54eb4b2f125b26f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1687:5
[INFO] [stdout]   19:     0x5606b9e2ca38 - core::result::Result<T,E>::unwrap::hc0fc659a945988b7
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1089:23
[INFO] [stdout]   20:     0x5606b9e2a82b - rexpect_integration_tests::test_multi_rejection::hb8edb0a878a73f61
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:136:5
[INFO] [stdout]   21:     0x5606b9e2a1b7 - rexpect_integration_tests::test_multi_rejection::{{closure}}::he77ccfeea26ce7a2
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:111:27
[INFO] [stdout]   22:     0x5606b9e2aef5 - core::ops::function::FnOnce::call_once::h17abbed3c948f04c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5606b9e6570f - core::ops::function::FnOnce::call_once::ha6a70e79f7481a7e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5606b9e6570f - test::__rust_begin_short_backtrace::hce33033019b61e69
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655:18
[INFO] [stdout]   25:     0x5606b9e32efc - test::run_test::{{closure}}::h858d3528838ae3e5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646:30
[INFO] [stdout]   26:     0x5606b9e32efc - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8ac2b00f01ae79e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5606b9e647e6 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h8093d25b522725ef
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   28:     0x5606b9e647e6 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h5c334c204e4a3913
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   29:     0x5606b9e647e6 - std::panicking::try::do_call::ha8b740c7b9478074
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   30:     0x5606b9e647e6 - std::panicking::try::h5d34f63d0e41b64e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   31:     0x5606b9e647e6 - std::panic::catch_unwind::hcfbb511e1aa7fc67
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   32:     0x5606b9e647e6 - test::run_test_in_process::h9abda90f802eaf21
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678:27
[INFO] [stdout]   33:     0x5606b9e647e6 - test::run_test::run_test_inner::{{closure}}::h0f058de91f6c6edf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572:39
[INFO] [stdout]   34:     0x5606b9e2d705 - test::run_test::run_test_inner::{{closure}}::h5ca48a4c59c6c1fb
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599:37
[INFO] [stdout]   35:     0x5606b9e2d705 - std::sys_common::backtrace::__rust_begin_short_backtrace::h32bd51e1495d4a33
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134:18
[INFO] [stdout]   36:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc086fae9b128b2db
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526:17
[INFO] [stdout]   37:     0x5606b9e32c41 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb66571888d270d43
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   38:     0x5606b9e32c41 - std::panicking::try::do_call::h4fa2ef4f6e51fecf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   39:     0x5606b9e32c41 - std::panicking::try::h1e9e22b8584489b5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   40:     0x5606b9e32c41 - std::panic::catch_unwind::h44698a3de0e0769e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   41:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::h50153abde3fe239e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525:30
[INFO] [stdout]   42:     0x5606b9e32c41 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbe5bbf6f38394364
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h0d83cb2920a49046
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   44:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hbe0a008aa7d9ef59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   45:     0x5606ba125865 - std::sys::unix::thread::Thread::new::thread_start::h3a466996bdf6b1f9
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   46:     0x7f20a9efcb43 - <unknown>
[INFO] [stdout]   47:     0x7f20a9f8dbb4 - __clone
[INFO] [stdout]   48:                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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/convert/mod.rs:727
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655
[INFO] [stdout]   12: test::run_test::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   13: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572
[INFO] [stdout]   14: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134
[INFO] [stdout]   15: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108
[INFO] [stdout]   17: <unknown>
[INFO] [stdout]   18: __clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:107:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5606ba11e14a - std::backtrace_rs::backtrace::libunwind::trace::h2f05d0192276a8f6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x5606ba11e14a - std::backtrace_rs::backtrace::trace_unsynchronized::hedc23dff7a1af150
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5606ba11e14a - std::sys_common::backtrace::_print_fmt::h17541ef7592f3ce2
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:65:5
[INFO] [stdout]    3:     0x5606ba11e14a - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h20f75df5ab0c0792
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x5606ba1434ef - core::fmt::write::h78109e6f466b3af6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/fmt/mod.rs:1254:17
[INFO] [stdout]    5:     0x5606ba11ad75 - std::io::Write::write_fmt::hd24bfb68a1b35a25
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/io/mod.rs:1698:15
[INFO] [stdout]    6:     0x5606ba11df15 - std::sys_common::backtrace::_print::h8dac57a9f8fbf9a5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    7:     0x5606ba11df15 - std::sys_common::backtrace::print::hf9ecbf41d1aa712f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    8:     0x5606ba11fc4e - std::panicking::default_hook::{{closure}}::h04c07acfc84ca028
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:269:22
[INFO] [stdout]    9:     0x5606ba11f981 - std::panicking::default_hook::h01f6bb23e1eceb2c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:285:9
[INFO] [stdout]   10:     0x5606b9e6083b - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h87fe9f7b2fd49e4c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   11:     0x5606b9e6083b - test::test_main::{{closure}}::he94d679243a7531b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:134:21
[INFO] [stdout]   12:     0x5606ba120334 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h3f2d6d8b14c4769f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   13:     0x5606ba120334 - std::panicking::rust_panic_with_hook::h2bb3722bbaf2f6df
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:695:13
[INFO] [stdout]   14:     0x5606ba1200a9 - std::panicking::begin_panic_handler::{{closure}}::h91bc7f36a4be011b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:582:13
[INFO] [stdout]   15:     0x5606ba11e5b6 - std::sys_common::backtrace::__rust_end_short_backtrace::h8b3d3717114f9e59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:150:18
[INFO] [stdout]   16:     0x5606ba11fe02 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:578:5
[INFO] [stdout]   17:     0x5606b9e28413 - core::panicking::panic_fmt::hfd368e3fbdabea26
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panicking.rs:67:14
[INFO] [stdout]   18:     0x5606b9e289b3 - core::result::unwrap_failed::he54eb4b2f125b26f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1687:5
[INFO] [stdout]   19:     0x5606b9e2ca38 - core::result::Result<T,E>::unwrap::hc0fc659a945988b7
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1089:23
[INFO] [stdout]   20:     0x5606b9e2a110 - rexpect_integration_tests::test_multi_success::h33345ce7cea57145
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:107:5
[INFO] [stdout]   21:     0x5606b9e29b07 - rexpect_integration_tests::test_multi_success::{{closure}}::he78bb9632b2274a1
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:83:25
[INFO] [stdout]   22:     0x5606b9e2af85 - core::ops::function::FnOnce::call_once::h91463396f9aa1062
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5606b9e6570f - core::ops::function::FnOnce::call_once::ha6a70e79f7481a7e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5606b9e6570f - test::__rust_begin_short_backtrace::hce33033019b61e69
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655:18
[INFO] [stdout]   25:     0x5606b9e32efc - test::run_test::{{closure}}::h858d3528838ae3e5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646:30
[INFO] [stdout]   26:     0x5606b9e32efc - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8ac2b00f01ae79e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5606b9e647e6 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h8093d25b522725ef
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   28:     0x5606b9e647e6 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h5c334c204e4a3913
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   29:     0x5606b9e647e6 - std::panicking::try::do_call::ha8b740c7b9478074
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   30:     0x5606b9e647e6 - std::panicking::try::h5d34f63d0e41b64e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   31:     0x5606b9e647e6 - std::panic::catch_unwind::hcfbb511e1aa7fc67
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   32:     0x5606b9e647e6 - test::run_test_in_process::h9abda90f802eaf21
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678:27
[INFO] [stdout]   33:     0x5606b9e647e6 - test::run_test::run_test_inner::{{closure}}::h0f058de91f6c6edf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572:39
[INFO] [stdout]   34:     0x5606b9e2d705 - test::run_test::run_test_inner::{{closure}}::h5ca48a4c59c6c1fb
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599:37
[INFO] [stdout]   35:     0x5606b9e2d705 - std::sys_common::backtrace::__rust_begin_short_backtrace::h32bd51e1495d4a33
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134:18
[INFO] [stdout]   36:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc086fae9b128b2db
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526:17
[INFO] [stdout]   37:     0x5606b9e32c41 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb66571888d270d43
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   38:     0x5606b9e32c41 - std::panicking::try::do_call::h4fa2ef4f6e51fecf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   39:     0x5606b9e32c41 - std::panicking::try::h1e9e22b8584489b5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   40:     0x5606b9e32c41 - std::panic::catch_unwind::h44698a3de0e0769e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   41:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::h50153abde3fe239e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525:30
[INFO] [stdout]   42:     0x5606b9e32c41 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbe5bbf6f38394364
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h0d83cb2920a49046
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   44:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hbe0a008aa7d9ef59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   45:     0x5606ba125865 - std::sys::unix::thread::Thread::new::thread_start::h3a466996bdf6b1f9
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   46:     0x7f20a9efcb43 - <unknown>
[INFO] [stdout]   47:     0x7f20a9f8dbb4 - __clone
[INFO] [stdout]   48:                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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/convert/mod.rs:727
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655
[INFO] [stdout]   12: test::run_test::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   13: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572
[INFO] [stdout]   14: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134
[INFO] [stdout]   15: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108
[INFO] [stdout]   17: <unknown>
[INFO] [stdout]   18: __clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:79:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5606ba11e14a - std::backtrace_rs::backtrace::libunwind::trace::h2f05d0192276a8f6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x5606ba11e14a - std::backtrace_rs::backtrace::trace_unsynchronized::hedc23dff7a1af150
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5606ba11e14a - std::sys_common::backtrace::_print_fmt::h17541ef7592f3ce2
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:65:5
[INFO] [stdout]    3:     0x5606ba11e14a - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h20f75df5ab0c0792
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x5606ba1434ef - core::fmt::write::h78109e6f466b3af6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/fmt/mod.rs:1254:17
[INFO] [stdout]    5:     0x5606ba11ad75 - std::io::Write::write_fmt::hd24bfb68a1b35a25
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/io/mod.rs:1698:15
[INFO] [stdout]    6:     0x5606ba11df15 - std::sys_common::backtrace::_print::h8dac57a9f8fbf9a5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    7:     0x5606ba11df15 - std::sys_common::backtrace::print::hf9ecbf41d1aa712f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    8:     0x5606ba11fc4e - std::panicking::default_hook::{{closure}}::h04c07acfc84ca028
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:269:22
[INFO] [stdout]    9:     0x5606ba11f981 - std::panicking::default_hook::h01f6bb23e1eceb2c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:285:9
[INFO] [stdout]   10:     0x5606b9e6083b - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h87fe9f7b2fd49e4c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   11:     0x5606b9e6083b - test::test_main::{{closure}}::he94d679243a7531b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:134:21
[INFO] [stdout]   12:     0x5606ba120334 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h3f2d6d8b14c4769f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   13:     0x5606ba120334 - std::panicking::rust_panic_with_hook::h2bb3722bbaf2f6df
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:695:13
[INFO] [stdout]   14:     0x5606ba1200a9 - std::panicking::begin_panic_handler::{{closure}}::h91bc7f36a4be011b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:582:13
[INFO] [stdout]   15:     0x5606ba11e5b6 - std::sys_common::backtrace::__rust_end_short_backtrace::h8b3d3717114f9e59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:150:18
[INFO] [stdout]   16:     0x5606ba11fe02 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:578:5
[INFO] [stdout]   17:     0x5606b9e28413 - core::panicking::panic_fmt::hfd368e3fbdabea26
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panicking.rs:67:14
[INFO] [stdout]   18:     0x5606b9e289b3 - core::result::unwrap_failed::he54eb4b2f125b26f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1687:5
[INFO] [stdout]   19:     0x5606b9e2ca38 - core::result::Result<T,E>::unwrap::hc0fc659a945988b7
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1089:23
[INFO] [stdout]   20:     0x5606b9e29a55 - rexpect_integration_tests::test_basic_rejection::h5e3320e68c8ef2ad
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:79:5
[INFO] [stdout]   21:     0x5606b9e29567 - rexpect_integration_tests::test_basic_rejection::{{closure}}::hc353c0529c517054
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:61:27
[INFO] [stdout]   22:     0x5606b9e2af35 - core::ops::function::FnOnce::call_once::h332cc9b6f6d436b5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5606b9e6570f - core::ops::function::FnOnce::call_once::ha6a70e79f7481a7e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5606b9e6570f - test::__rust_begin_short_backtrace::hce33033019b61e69
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655:18
[INFO] [stdout]   25:     0x5606b9e32efc - test::run_test::{{closure}}::h858d3528838ae3e5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646:30
[INFO] [stdout]   26:     0x5606b9e32efc - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8ac2b00f01ae79e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5606b9e647e6 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h8093d25b522725ef
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   28:     0x5606b9e647e6 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h5c334c204e4a3913
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   29:     0x5606b9e647e6 - std::panicking::try::do_call::ha8b740c7b9478074
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   30:     0x5606b9e647e6 - std::panicking::try::h5d34f63d0e41b64e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   31:     0x5606b9e647e6 - std::panic::catch_unwind::hcfbb511e1aa7fc67
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   32:     0x5606b9e647e6 - test::run_test_in_process::h9abda90f802eaf21
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678:27
[INFO] [stdout]   33:     0x5606b9e647e6 - test::run_test::run_test_inner::{{closure}}::h0f058de91f6c6edf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572:39
[INFO] [stdout]   34:     0x5606b9e2d705 - test::run_test::run_test_inner::{{closure}}::h5ca48a4c59c6c1fb
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599:37
[INFO] [stdout]   35:     0x5606b9e2d705 - std::sys_common::backtrace::__rust_begin_short_backtrace::h32bd51e1495d4a33
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134:18
[INFO] [stdout]   36:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc086fae9b128b2db
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526:17
[INFO] [stdout]   37:     0x5606b9e32c41 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb66571888d270d43
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   38:     0x5606b9e32c41 - std::panicking::try::do_call::h4fa2ef4f6e51fecf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   39:     0x5606b9e32c41 - std::panicking::try::h1e9e22b8584489b5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   40:     0x5606b9e32c41 - std::panic::catch_unwind::h44698a3de0e0769e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   41:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::h50153abde3fe239e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525:30
[INFO] [stdout]   42:     0x5606b9e32c41 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbe5bbf6f38394364
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h0d83cb2920a49046
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   44:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hbe0a008aa7d9ef59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   45:     0x5606ba125865 - std::sys::unix::thread::Thread::new::thread_start::h3a466996bdf6b1f9
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   46:     0x7f20a9efcb43 - <unknown>
[INFO] [stdout]   47:     0x7f20a9f8dbb4 - __clone
[INFO] [stdout]   48:                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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/error-chain-0.12.2/src/error_chain.rs:370
[INFO] [stdout]    4: <T as core::convert::Into<U>>::into
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/convert/mod.rs:727
[INFO] [stdout]    5: rexpect::reader::NBReader::read_until
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/index.crates.io-6f17d22bba15001f/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/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   11: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655
[INFO] [stdout]   12: test::run_test::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   13: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572
[INFO] [stdout]   14: test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599
[INFO] [stdout]       std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134
[INFO] [stdout]   15: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140
[INFO] [stdout]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250
[INFO] [stdout]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108
[INFO] [stdout]   17: <unknown>
[INFO] [stdout]   18: __clone
[INFO] [stdout] ) } })', tests/rexpect_integration_tests.rs:57:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5606ba11e14a - std::backtrace_rs::backtrace::libunwind::trace::h2f05d0192276a8f6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
[INFO] [stdout]    1:     0x5606ba11e14a - std::backtrace_rs::backtrace::trace_unsynchronized::hedc23dff7a1af150
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x5606ba11e14a - std::sys_common::backtrace::_print_fmt::h17541ef7592f3ce2
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:65:5
[INFO] [stdout]    3:     0x5606ba11e14a - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h20f75df5ab0c0792
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x5606ba1434ef - core::fmt::write::h78109e6f466b3af6
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/fmt/mod.rs:1254:17
[INFO] [stdout]    5:     0x5606ba11ad75 - std::io::Write::write_fmt::hd24bfb68a1b35a25
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/io/mod.rs:1698:15
[INFO] [stdout]    6:     0x5606ba11df15 - std::sys_common::backtrace::_print::h8dac57a9f8fbf9a5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    7:     0x5606ba11df15 - std::sys_common::backtrace::print::hf9ecbf41d1aa712f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    8:     0x5606ba11fc4e - std::panicking::default_hook::{{closure}}::h04c07acfc84ca028
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:269:22
[INFO] [stdout]    9:     0x5606ba11f981 - std::panicking::default_hook::h01f6bb23e1eceb2c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:285:9
[INFO] [stdout]   10:     0x5606b9e6083b - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h87fe9f7b2fd49e4c
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   11:     0x5606b9e6083b - test::test_main::{{closure}}::he94d679243a7531b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:134:21
[INFO] [stdout]   12:     0x5606ba120334 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h3f2d6d8b14c4769f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1987:9
[INFO] [stdout]   13:     0x5606ba120334 - std::panicking::rust_panic_with_hook::h2bb3722bbaf2f6df
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:695:13
[INFO] [stdout]   14:     0x5606ba1200a9 - std::panicking::begin_panic_handler::{{closure}}::h91bc7f36a4be011b
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:582:13
[INFO] [stdout]   15:     0x5606ba11e5b6 - std::sys_common::backtrace::__rust_end_short_backtrace::h8b3d3717114f9e59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:150:18
[INFO] [stdout]   16:     0x5606ba11fe02 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:578:5
[INFO] [stdout]   17:     0x5606b9e28413 - core::panicking::panic_fmt::hfd368e3fbdabea26
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panicking.rs:67:14
[INFO] [stdout]   18:     0x5606b9e289b3 - core::result::unwrap_failed::he54eb4b2f125b26f
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1687:5
[INFO] [stdout]   19:     0x5606b9e2ca38 - core::result::Result<T,E>::unwrap::hc0fc659a945988b7
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/result.rs:1089:23
[INFO] [stdout]   20:     0x5606b9e294b5 - rexpect_integration_tests::test_basic_usage::h03f7ad545567aee3
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:57:5
[INFO] [stdout]   21:     0x5606b9e28fc7 - rexpect_integration_tests::test_basic_usage::{{closure}}::hfe6f52fe51ab4e09
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/rexpect_integration_tests.rs:39:23
[INFO] [stdout]   22:     0x5606b9e2afc5 - core::ops::function::FnOnce::call_once::hb3ddf4da778541f8
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5606b9e6570f - core::ops::function::FnOnce::call_once::ha6a70e79f7481a7e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5606b9e6570f - test::__rust_begin_short_backtrace::hce33033019b61e69
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:655:18
[INFO] [stdout]   25:     0x5606b9e32efc - test::run_test::{{closure}}::h858d3528838ae3e5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:646:30
[INFO] [stdout]   26:     0x5606b9e32efc - core::ops::function::FnOnce::call_once{{vtable.shim}}::he8ac2b00f01ae79e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5606b9e647e6 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h8093d25b522725ef
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   28:     0x5606b9e647e6 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h5c334c204e4a3913
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   29:     0x5606b9e647e6 - std::panicking::try::do_call::ha8b740c7b9478074
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   30:     0x5606b9e647e6 - std::panicking::try::h5d34f63d0e41b64e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   31:     0x5606b9e647e6 - std::panic::catch_unwind::hcfbb511e1aa7fc67
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   32:     0x5606b9e647e6 - test::run_test_in_process::h9abda90f802eaf21
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:678:27
[INFO] [stdout]   33:     0x5606b9e647e6 - test::run_test::run_test_inner::{{closure}}::h0f058de91f6c6edf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:572:39
[INFO] [stdout]   34:     0x5606b9e2d705 - test::run_test::run_test_inner::{{closure}}::h5ca48a4c59c6c1fb
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/test/src/lib.rs:599:37
[INFO] [stdout]   35:     0x5606b9e2d705 - std::sys_common::backtrace::__rust_begin_short_backtrace::h32bd51e1495d4a33
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys_common/backtrace.rs:134:18
[INFO] [stdout]   36:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc086fae9b128b2db
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:526:17
[INFO] [stdout]   37:     0x5606b9e32c41 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb66571888d270d43
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/panic/unwind_safe.rs:271:9
[INFO] [stdout]   38:     0x5606b9e32c41 - std::panicking::try::do_call::h4fa2ef4f6e51fecf
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:485:40
[INFO] [stdout]   39:     0x5606b9e32c41 - std::panicking::try::h1e9e22b8584489b5
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panicking.rs:449:19
[INFO] [stdout]   40:     0x5606b9e32c41 - std::panic::catch_unwind::h44698a3de0e0769e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/panic.rs:140:14
[INFO] [stdout]   41:     0x5606b9e32c41 - std::thread::Builder::spawn_unchecked_::{{closure}}::h50153abde3fe239e
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/thread/mod.rs:525:30
[INFO] [stdout]   42:     0x5606b9e32c41 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbe5bbf6f38394364
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h0d83cb2920a49046
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   44:     0x5606ba125865 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hbe0a008aa7d9ef59
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/alloc/src/boxed.rs:1973:9
[INFO] [stdout]   45:     0x5606ba125865 - std::sys::unix::thread::Thread::new::thread_start::h3a466996bdf6b1f9
[INFO] [stdout]                                at /rustc/1b7dd2252b99671ce5d1cb9664c5f8636329436d/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   46:     0x7f20a9efcb43 - <unknown>
[INFO] [stdout]   47:     0x7f20a9f8dbb4 - __clone
[INFO] [stdout]   48:                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.58s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test rexpect_integration_tests`
[INFO] running `Command { std: "docker" "inspect" "899975e63e9d5d53a5adf41af99de9f7f1cd3a909ead6b3fae9c23fd625e1ab8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "899975e63e9d5d53a5adf41af99de9f7f1cd3a909ead6b3fae9c23fd625e1ab8", kill_on_drop: false }`
[INFO] [stdout] 899975e63e9d5d53a5adf41af99de9f7f1cd3a909ead6b3fae9c23fd625e1ab8
