[INFO] fetching crate rexpect 0.5.0... [INFO] testing rexpect-0.5.0 against master#1871252fc8bb672d40787e67404e6eaae7059369 for pr-125151 [INFO] extracting crate rexpect 0.5.0 into /workspace/builds/worker-4-tc1/source [INFO] validating manifest of crates.io crate rexpect 0.5.0 on toolchain 1871252fc8bb672d40787e67404e6eaae7059369 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate rexpect 0.5.0 [INFO] finished tweaking crates.io crate rexpect 0.5.0 [INFO] tweaked toml for crates.io crate rexpect 0.5.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml [INFO] crate crates.io crate rexpect 0.5.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" "+1871252fc8bb672d40787e67404e6eaae7059369" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded thiserror-impl v1.0.34 [INFO] [stderr] Downloaded thiserror v1.0.34 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 05d219cf7218a43a668d67a0f5ba1e49576a7f9fb47a9e37bef99ef6a832e397 [INFO] running `Command { std: "docker" "start" "-a" "05d219cf7218a43a668d67a0f5ba1e49576a7f9fb47a9e37bef99ef6a832e397", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "05d219cf7218a43a668d67a0f5ba1e49576a7f9fb47a9e37bef99ef6a832e397", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "05d219cf7218a43a668d67a0f5ba1e49576a7f9fb47a9e37bef99ef6a832e397", kill_on_drop: false }` [INFO] [stdout] 05d219cf7218a43a668d67a0f5ba1e49576a7f9fb47a9e37bef99ef6a832e397 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 80d81b98e35ead2e9dbf7a1ffb54a9c42a7a76a1dd60c82570f7903e2ddc5e5a [INFO] running `Command { std: "docker" "start" "-a" "80d81b98e35ead2e9dbf7a1ffb54a9c42a7a76a1dd60c82570f7903e2ddc5e5a", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.43 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling unicode-ident v1.0.3 [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling libc v0.2.132 [INFO] [stderr] Compiling syn v1.0.99 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling fastrand v1.8.0 [INFO] [stderr] Compiling remove_dir_all v0.5.3 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling regex-syntax v0.6.27 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling comma v1.0.0 [INFO] [stderr] Compiling memoffset v0.6.5 [INFO] [stderr] Compiling aho-corasick v0.7.19 [INFO] [stderr] Compiling nix v0.25.0 [INFO] [stderr] Compiling tempfile v3.3.0 [INFO] [stderr] Compiling regex v1.6.0 [INFO] [stderr] Compiling thiserror-impl v1.0.34 [INFO] [stderr] Compiling thiserror v1.0.34 [INFO] [stderr] Compiling rexpect v0.5.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.61s [INFO] running `Command { std: "docker" "inspect" "80d81b98e35ead2e9dbf7a1ffb54a9c42a7a76a1dd60c82570f7903e2ddc5e5a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "80d81b98e35ead2e9dbf7a1ffb54a9c42a7a76a1dd60c82570f7903e2ddc5e5a", kill_on_drop: false }` [INFO] [stdout] 80d81b98e35ead2e9dbf7a1ffb54a9c42a7a76a1dd60c82570f7903e2ddc5e5a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f17388194946b9413f1a2f5a5e740cfbb0263e2c0a9e65a0df98c3eb2fb115b6 [INFO] running `Command { std: "docker" "start" "-a" "f17388194946b9413f1a2f5a5e740cfbb0263e2c0a9e65a0df98c3eb2fb115b6", kill_on_drop: false }` [INFO] [stderr] Compiling rexpect v0.5.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.77s [INFO] running `Command { std: "docker" "inspect" "f17388194946b9413f1a2f5a5e740cfbb0263e2c0a9e65a0df98c3eb2fb115b6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f17388194946b9413f1a2f5a5e740cfbb0263e2c0a9e65a0df98c3eb2fb115b6", kill_on_drop: false }` [INFO] [stdout] f17388194946b9413f1a2f5a5e740cfbb0263e2c0a9e65a0df98c3eb2fb115b6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:59a85a07ab18ca8720692f8e61effa1c651d9e2ca591e072c2b212bb91a6b8b5" "/opt/rustwide/cargo-home/bin/cargo" "+1871252fc8bb672d40787e67404e6eaae7059369" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 13a864ece8a048577d2233e9f3f6f8348bbd4226cd52957e2bb6d28095abbd5e [INFO] running `Command { std: "docker" "start" "-a" "13a864ece8a048577d2233e9f3f6f8348bbd4226cd52957e2bb6d28095abbd5e", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rexpect-508c5e4067c1c982) [INFO] [stdout] [INFO] [stdout] running 20 tests [INFO] [stdout] test session::tests::test_expect_empty_command_error ... ok [INFO] [stdout] test reader::tests::test_regex2 ... ok [INFO] [stdout] test reader::tests::test_regex ... ok [INFO] [stdout] test reader::tests::test_any_with_multiple_possible_matches ... ok [INFO] [stdout] test reader::tests::test_expect_melon ... ok [INFO] [stdout] test reader::tests::test_any_with_same_start_different_length ... ok [INFO] [stdout] test reader::tests::test_nbytes ... ok [INFO] [stdout] test session::tests::test_tokenize_command ... ok [INFO] [stdout] test reader::tests::test_try_read ... ok [INFO] [stdout] test reader::tests::test_eof ... ok [INFO] [stdout] test process::tests::test_cat ... ok [INFO] [stdout] test session::tests::test_expect_string ... ok [INFO] [stdout] test session::tests::test_expect_any ... ok [INFO] [stdout] test session::tests::test_read_line ... ok [INFO] [stdout] test session::tests::test_read_string_before ... ok [INFO] [stdout] test session::tests::test_expect_eof_timeout ... ok [INFO] [stdout] test session::tests::test_expect_eof_timeout2 ... ok [INFO] [stdout] test session::tests::test_kill_timeout ... ok [INFO] [stdout] test session::tests::test_bash ... ok [INFO] [stdout] test session::tests::test_bash_control_chars ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.72s [INFO] [stdout] [INFO] [stderr] Doc-tests rexpect [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test src/lib.rs - (line 49) - compile ... ok [INFO] [stdout] test src/process.rs - process::PtyProcess::status (line 152) - compile ... ok [INFO] [stdout] test src/lib.rs - (line 17) - compile ... ok [INFO] [stdout] test src/process.rs - process::PtyProcess (line 28) ... ok [INFO] [stdout] test src/session.rs - session::PtySession (line 178) ... FAILED [INFO] [stdout] test src/reader.rs - reader::NBReader::read_until (line 205) ... ok [INFO] [stdout] test src/session.rs - session::StreamSession::exp_any (line 135) ... ok [INFO] [stdout] test src/session.rs - session::PtyReplSession::execute (line 284) ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- src/session.rs - session::PtySession (line 178) stdout ---- [INFO] [stdout] Test executable failed (exit status: 101). [INFO] [stdout] [INFO] [stdout] stderr: [INFO] [stdout] thread 'main' panicked at src/session.rs:13:1: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: "hello, polly!" [INFO] [stdout] right: "hello, polly!hello, polly!" [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55f4de24ea55 - std::backtrace_rs::backtrace::libunwind::trace::h59d96bdb08384354 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/../../backtrace/src/backtrace/libunwind.rs:105:5 [INFO] [stdout] 1: 0x55f4de24ea55 - std::backtrace_rs::backtrace::trace_unsynchronized::h9cf5becacfc93fba [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55f4de24ea55 - std::sys_common::backtrace::_print_fmt::h10b76d10405dbd48 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55f4de24ea55 - ::fmt::h6ed9e62a156d84e4 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55f4de26e57b - core::fmt::rt::Argument::fmt::h645c680983f03c9f [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/core/src/fmt/rt.rs:165:63 [INFO] [stdout] 5: 0x55f4de26e57b - core::fmt::write::h8bcd80919a02be29 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/core/src/fmt/mod.rs:1169:21 [INFO] [stdout] 6: 0x55f4de24c93f - std::io::Write::write_fmt::hed769ca6c29434ec [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/io/mod.rs:1835:15 [INFO] [stdout] 7: 0x55f4de24e82e - std::sys_common::backtrace::_print::h6306f131a28d62b0 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55f4de24e82e - std::sys_common::backtrace::print::h7079288e0a26dfcc [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55f4de24fb99 - std::panicking::default_hook::{{closure}}::hb063ecec81a736ba [INFO] [stdout] 10: 0x55f4de24f8dd - std::panicking::default_hook::hd56ee406bf547b5c [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:298:9 [INFO] [stdout] 11: 0x55f4de250033 - std::panicking::rust_panic_with_hook::h624aa3ca42ebb8f2 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:795:13 [INFO] [stdout] 12: 0x55f4de24ff14 - std::panicking::begin_panic_handler::{{closure}}::hbc4e76194a5e287c [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:664:13 [INFO] [stdout] 13: 0x55f4de24ef19 - std::sys_common::backtrace::__rust_end_short_backtrace::h847fedc9d1ff7b6d [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 14: 0x55f4de24fc47 - rust_begin_unwind [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:652:5 [INFO] [stdout] 15: 0x55f4de18b3d3 - core::panicking::panic_fmt::hec11a924b87ce965 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/core/src/panicking.rs:72:14 [INFO] [stdout] 16: 0x55f4de18b77e - core::panicking::assert_failed_inner::h01c8f7f0c1caba58 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/core/src/panicking.rs:408:17 [INFO] [stdout] 17: 0x55f4de18c4ae - core::panicking::assert_failed::hd053d42cf1465113 [INFO] [stdout] 18: 0x55f4de18d25d - rust_out::main::{{closure}}::hb5ee2861876fcc4c [INFO] [stdout] 19: 0x55f4de18cdd3 - rust_out::main::h696a342f0ae7cd72 [INFO] [stdout] 20: 0x55f4de18bde3 - core::ops::function::FnOnce::call_once::hacff659aa20c7b40 [INFO] [stdout] 21: 0x55f4de18bcb6 - std::sys_common::backtrace::__rust_begin_short_backtrace::hcc6b8bea3112e884 [INFO] [stdout] 22: 0x55f4de18bd09 - std::rt::lang_start::{{closure}}::h4db0ca8543e29a47 [INFO] [stdout] 23: 0x55f4de249acd - core::ops::function::impls:: for &F>::call_once::h239f0051f1e18680 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/core/src/ops/function.rs:284:13 [INFO] [stdout] 24: 0x55f4de249acd - std::panicking::try::do_call::hf7072797cbfea7fa [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:559:40 [INFO] [stdout] 25: 0x55f4de249acd - std::panicking::try::h3a37e38691a5244b [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:523:19 [INFO] [stdout] 26: 0x55f4de249acd - std::panic::catch_unwind::hc7cc1460e4236209 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panic.rs:149:14 [INFO] [stdout] 27: 0x55f4de249acd - std::rt::lang_start_internal::{{closure}}::hce632608b277cffc [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/rt.rs:141:48 [INFO] [stdout] 28: 0x55f4de249acd - std::panicking::try::do_call::hafc0f13e6abd9d79 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:559:40 [INFO] [stdout] 29: 0x55f4de249acd - std::panicking::try::h67b8f1800ef15799 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panicking.rs:523:19 [INFO] [stdout] 30: 0x55f4de249acd - std::panic::catch_unwind::he5b260dc522c68be [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/panic.rs:149:14 [INFO] [stdout] 31: 0x55f4de249acd - std::rt::lang_start_internal::hf119eb75d1ffe221 [INFO] [stdout] at /rustc/1871252fc8bb672d40787e67404e6eaae7059369/library/std/src/rt.rs:141:20 [INFO] [stdout] 32: 0x55f4de18bce7 - std::rt::lang_start::ha9360cc92730957e [INFO] [stdout] 33: 0x55f4de18d2d5 - main [INFO] [stdout] 34: 0x7feabff5bd90 - [INFO] [stdout] 35: 0x7feabff5be40 - __libc_start_main [INFO] [stdout] 36: 0x55f4de18bbe5 - _start [INFO] [stdout] 37: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] src/session.rs - session::PtySession (line 178) [INFO] [stdout] [INFO] [stdout] test result: FAILED. 7 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.15s [INFO] [stdout] [INFO] [stderr] error: doctest failed, to rerun pass `--doc` [INFO] running `Command { std: "docker" "inspect" "13a864ece8a048577d2233e9f3f6f8348bbd4226cd52957e2bb6d28095abbd5e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "13a864ece8a048577d2233e9f3f6f8348bbd4226cd52957e2bb6d28095abbd5e", kill_on_drop: false }` [INFO] [stdout] 13a864ece8a048577d2233e9f3f6f8348bbd4226cd52957e2bb6d28095abbd5e