[INFO] fetching crate epsh 0.0.6...
[INFO] testing epsh-0.0.6 against 1.97.0-beta.6 for beta-1.98-1
[INFO] extracting crate epsh 0.0.6 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate epsh 0.0.6
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate epsh 0.0.6
[INFO] tweaked toml for crates.io crate epsh 0.0.6 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate epsh 0.0.6 on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate epsh 0.0.6 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" "+1.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a
[INFO] running `Command { std: "docker" "start" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling epsh v0.0.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.31s
[INFO] running `Command { std: "docker" "inspect" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling epsh v0.0.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.57s
[INFO] running `Command { std: "docker" "inspect" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/epsh-5cdfca4d2d37e355)
[INFO] [stdout] 
[INFO] [stdout] running 143 tests
[INFO] [stdout] test arith::tests::assignment ... ok
[INFO] [stdout] test arith::tests::bitwise ... ok
[INFO] [stdout] test arith::tests::basic_arithmetic ... ok
[INFO] [stdout] test arith::tests::compound_assignment ... ok
[INFO] [stdout] test arith::tests::division_by_zero ... ok
[INFO] [stdout] test arith::tests::complex_expr ... ok
[INFO] [stdout] test arith::tests::nested_parens ... ok
[INFO] [stdout] test arith::tests::hex_octal ... ok
[INFO] [stdout] test arith::tests::precedence ... ok
[INFO] [stdout] test encoding::tests::pure_utf8_passthrough ... ok
[INFO] [stdout] test arith::tests::ternary ... ok
[INFO] [stdout] test arith::tests::comparison ... ok
[INFO] [stdout] test arith::tests::logical ... ok
[INFO] [stdout] test arith::tests::variables ... ok
[INFO] [stdout] hello
[INFO] [stdout] test arith::tests::unary ... ok
[INFO] [stdout] test encoding::tests::roundtrip_ascii ... ok
[INFO] [stdout] test encoding::tests::roundtrip_invalid_byte ... ok
[INFO] [stdout] test encoding::tests::roundtrip_mixed ... ok
[INFO] [stdout] test eval::tests::run_and_list ... ok
[INFO] [stdout] test encoding::tests::roundtrip_utf8 ... ok
[INFO] [stdout] test eval::tests::run_exit_status ... ok
[INFO] [stdout] test eval::tests::run_arithmetic_expansion ... ok
[INFO] [stdout] test eval::tests::run_arithmetic_with_variables ... ok
[INFO] [stdout] test eval::tests::run_case ... ok
[INFO] [stdout] test eval::tests::run_command_substitution ... ok
[INFO] [stdout] test eval::tests::run_command_substitution_backtick ... ok
[INFO] [stdout] test eval::tests::run_command_substitution_in_args ... ok
[INFO] [stdout] test eval::tests::run_echo ... ok
[INFO] [stdout] test eval::tests::run_arithmetic_assignment ... ok
[INFO] [stdout] test eval::tests::run_function ... ok
[INFO] [stdout] test eval::tests::run_for_loop ... ok
[INFO] [stdout] test eval::tests::run_local_vars ... ok
[INFO] [stdout] test eval::tests::run_if_false ... ok
[INFO] [stdout] test eval::tests::run_negation ... ok
[INFO] [stdout] test eval::tests::run_if_true ... ok
[INFO] [stdout] test eval::tests::run_true_false ... ok
[INFO] [stdout] test eval::tests::run_variable_assignment ... ok
[INFO] [stdout] test eval::tests::run_variable_expansion ... ok
[INFO] [stdout] test eval::tests::run_nested_command_substitution ... ok
[INFO] [stdout] test eval::tests::run_set_errexit ... ok
[INFO] [stdout] test eval::tests::run_while_with_arithmetic ... ok
[INFO] [stdout] test eval::tests::run_while_loop ... ok
[INFO] [stdout] test eval::tests::run_shift ... ok
[INFO] [stdout] test eval::tests::run_or_list ... ok
[INFO] [stdout] test expand::tests::expand_alternative_unset ... ok
[INFO] [stdout] test eval::tests::run_heredoc ... ok
[INFO] [stdout] test expand::tests::expand_alternative_set ... ok
[INFO] [stdout] test expand::tests::expand_assign_default ... ok
[INFO] [stdout] test eval::tests::run_subshell ... ok
[INFO] [stdout] test eval::tests::run_test_file_ops ... ok
[INFO] [stdout] test expand::tests::expand_default_set ... ok
[INFO] [stdout] test eval::tests::run_trap ... ok
[INFO] [stdout] test expand::tests::expand_default_colon_empty ... ok
[INFO] [stdout] test expand::tests::expand_default_unset ... ok
[INFO] [stdout] test expand::tests::expand_length ... ok
[INFO] [stdout] test expand::tests::expand_simple_variable ... ok
[INFO] [stdout] test expand::tests::expand_exit_status ... ok
[INFO] [stdout] test expand::tests::expand_single_quoted ... ok
[INFO] [stdout] test expand::tests::field_split_basic ... ok
[INFO] [stdout] test expand::tests::field_split_custom_ifs ... ok
[INFO] [stdout] test expand::tests::expand_trim_suffix_large ... ok
[INFO] [stdout] test expand::tests::expand_trim_suffix_small ... ok
[INFO] [stdout] test eval::tests::run_test_compound ... ok
[INFO] [stdout] test eval::tests::run_test_builtin ... ok
[INFO] [stdout] test expand::tests::expand_tilde_home ... ok
[INFO] [stdout] test expand::tests::field_split_mixed ... ok
[INFO] [stdout] test expand::tests::expand_trim_prefix_small ... ok
[INFO] [stdout] test glob::tests::fnmatch_bracket ... ok
[INFO] [stdout] test expand::tests::expand_literal ... ok
[INFO] [stdout] test expand::tests::expand_trim_prefix_large ... ok
[INFO] [stdout] test expand::tests::expand_double_quoted_no_split ... ok
[INFO] [stdout] test glob::tests::fnmatch_bracket_negate ... ok
[INFO] [stdout] test glob::tests::fnmatch_bracket_range ... ok
[INFO] [stdout] test glob::tests::fnmatch_escape ... ok
[INFO] [stdout] test glob::tests::fnmatch_literal ... ok
[INFO] [stdout] test glob::tests::fnmatch_complex ... ok
[INFO] [stdout] test glob::tests::fnmatch_question ... ok
[INFO] [stdout] test glob::tests::fnmatch_star ... ok
[INFO] [stdout] test glob::tests::has_glob_chars_test ... ok
[INFO] [stdout] test lexer::tests::background ... ok
[INFO] [stdout] test lexer::tests::backslash_newline ... ok
[INFO] [stdout] test lexer::tests::case_tokens ... ok
[INFO] [stdout] test lexer::tests::comments ... ok
[INFO] [stdout] test lexer::tests::command_substitution ... ok
[INFO] [stdout] test lexer::tests::dollar_brace ... ok
[INFO] [stdout] test lexer::tests::double_quotes ... ok
[INFO] [stdout] test lexer::tests::empty_input ... ok
[INFO] [stdout] test lexer::tests::pipeline ... ok
[INFO] [stdout] test lexer::tests::arithmetic ... ok
[INFO] [stdout] test lexer::tests::and_or ... ok
[INFO] [stdout] test lexer::tests::heredoc_operator ... ok
[INFO] [stdout] test lexer::tests::assignment ... ok
[INFO] [stdout] test lexer::tests::redir_filename ... ok
[INFO] [stdout] test lexer::tests::redirections ... ok
[INFO] [stdout] test lexer::tests::single_quotes ... ok
[INFO] [stdout] test lexer::tests::unterminated_single_quote ... ok
[INFO] [stdout] test lexer::tests::simple_command ... ok
[INFO] [stdout] test parser::tests::and_or_list ... ok
[INFO] [stdout] test parser::tests::assignment ... ok
[INFO] [stdout] test parser::tests::assignment_with_command ... ok
[INFO] [stdout] test parser::tests::case_statement ... ok
[INFO] [stdout] test parser::tests::background ... ok
[INFO] [stdout] test parser::tests::empty_program ... ok
[INFO] [stdout] test parser::tests::elif_chain ... ok
[INFO] [stdout] test parser::tests::if_then_fi ... ok
[INFO] [stdout] test parser::tests::multiline_program ... ok
[INFO] [stdout] test parser::tests::negated_pipeline ... ok
[INFO] [stdout] test parser::tests::comments_only ... ok
[INFO] [stdout] test parser::tests::for_loop_without_words ... ok
[INFO] [stdout] test lexer::tests::reserved_words ... ok
[INFO] [stdout] test parser::tests::redirections ... ok
[INFO] [stdout] test parser::tests::trim_operations ... ok
[INFO] [stdout] test parser::tests::pipeline ... ok
[INFO] [stdout] test parser::tests::subshell ... ok
[INFO] [stdout] test parser::tests::until_loop ... ok
[INFO] [stdout] test parser::tests::for_loop_with_words ... ok
[INFO] [stdout] test parser::tests::word_parts_arith ... ok
[INFO] [stdout] test parser::tests::function_def ... ok
[INFO] [stdout] test parser::tests::word_parts_brace_default ... ok
[INFO] [stdout] test parser::tests::sequence ... ok
[INFO] [stdout] test parser::tests::simple_command ... ok
[INFO] [stdout] test parser::tests::brace_group ... ok
[INFO] [stdout] test parser::tests::if_then_else_fi ... ok
[INFO] [stdout] test parser::tests::while_loop ... ok
[INFO] [stdout] test parser::tests::word_parts_double_quoted_with_var ... ok
[INFO] [stdout] test parser::tests::word_parts_length ... ok
[INFO] [stdout] test parser::tests::word_parts_single_quoted ... ok
[INFO] [stdout] test parser::tests::word_parts_literal ... ok
[INFO] [stdout] test parser::tests::word_parts_variable ... ok
[INFO] [stdout] test parser::tests::word_parts_tilde ... ok
[INFO] [stdout] test var::tests::ifs_default ... ok
[INFO] [stdout] test parser::tests::word_parts_mixed ... ok
[INFO] [stdout] test parser::tests::word_parts_cmd_subst ... ok
[INFO] [stdout] test var::tests::positional_params ... ok
[INFO] [stdout] test var::tests::set_get ... ok
[INFO] [stdout] test var::tests::unset ... ok
[INFO] [stdout] test shell_bytes::tests::utf8_view ... ok
[INFO] [stdout] test var::tests::readonly ... ok
[INFO] [stdout] test shell_bytes::tests::shell_roundtrip ... ok
[INFO] [stdout] test var::tests::scope_new_local ... ok
[INFO] [stdout] test var::tests::scope_local ... ok
[INFO] [stdout] test eval::tests::heredoc_with_sinks_does_not_corrupt_fd0 ... ok
[INFO] [stdout] test eval::tests::run_pipeline_external has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a", kill_on_drop: false }`
[INFO] [stdout] aab5e93d5c9363214ac026a5e52585a17ec8c66107fc38da5533c2d0f70d105a
