[INFO] cloning repository https://github.com/joshuarli/epsh
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/joshuarli/epsh" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoshuarli%2Fepsh", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoshuarli%2Fepsh'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] cf563fee00ceea34d7b6cb42584801669a8de328
[INFO] testing joshuarli/epsh against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoshuarli%2Fepsh" "/workspace/builds/worker-6-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-6-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/joshuarli/epsh
[INFO] finished tweaking git repo https://github.com/joshuarli/epsh
[INFO] tweaked toml for git repo https://github.com/joshuarli/epsh written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/joshuarli/epsh on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/joshuarli/epsh 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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 6d5a5abc7d87ee4536a89f786639fb326175cb87bad29247ef0ee4d525535fcb
[INFO] running `Command { std: "docker" "start" "-a" "6d5a5abc7d87ee4536a89f786639fb326175cb87bad29247ef0ee4d525535fcb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6d5a5abc7d87ee4536a89f786639fb326175cb87bad29247ef0ee4d525535fcb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6d5a5abc7d87ee4536a89f786639fb326175cb87bad29247ef0ee4d525535fcb", kill_on_drop: false }`
[INFO] [stdout] 6d5a5abc7d87ee4536a89f786639fb326175cb87bad29247ef0ee4d525535fcb
[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=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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 770a0eab36f0c8d02a10a2fe1e40e147982046c8f9129dd9be12f6c684191482
[INFO] running `Command { std: "docker" "start" "-a" "770a0eab36f0c8d02a10a2fe1e40e147982046c8f9129dd9be12f6c684191482", 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 4.37s
[INFO] running `Command { std: "docker" "inspect" "770a0eab36f0c8d02a10a2fe1e40e147982046c8f9129dd9be12f6c684191482", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "770a0eab36f0c8d02a10a2fe1e40e147982046c8f9129dd9be12f6c684191482", kill_on_drop: false }`
[INFO] [stdout] 770a0eab36f0c8d02a10a2fe1e40e147982046c8f9129dd9be12f6c684191482
[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=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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5a96024f8b42e4ee8f8af18bdfd3d0f3923676cfed5829033657f59778760954
[INFO] running `Command { std: "docker" "start" "-a" "5a96024f8b42e4ee8f8af18bdfd3d0f3923676cfed5829033657f59778760954", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling fastrand v2.3.0
[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 6.25s
[INFO] running `Command { std: "docker" "inspect" "5a96024f8b42e4ee8f8af18bdfd3d0f3923676cfed5829033657f59778760954", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5a96024f8b42e4ee8f8af18bdfd3d0f3923676cfed5829033657f59778760954", kill_on_drop: false }`
[INFO] [stdout] 5a96024f8b42e4ee8f8af18bdfd3d0f3923676cfed5829033657f59778760954
[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=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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 758b1557f1aff9c8d516c40b65782e693dd7f53deaf302f9d912ed7978d8533f
[INFO] running `Command { std: "docker" "start" "-a" "758b1557f1aff9c8d516c40b65782e693dd7f53deaf302f9d912ed7978d8533f", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/epsh-b0b882bf51c9e17b)
[INFO] [stdout] running 143 tests
[INFO] [stdout] test arith::tests::complex_expr ... ok
[INFO] [stdout] test arith::tests::basic_arithmetic ... ok
[INFO] [stdout] test arith::tests::bitwise ... ok
[INFO] [stdout] test arith::tests::division_by_zero ... ok
[INFO] [stdout] test arith::tests::compound_assignment ... ok
[INFO] [stdout] test arith::tests::logical ... ok
[INFO] [stdout] test arith::tests::comparison ... ok
[INFO] [stdout] test arith::tests::hex_octal ... ok
[INFO] [stdout] test arith::tests::precedence ... ok
[INFO] [stdout] test arith::tests::variables ... ok
[INFO] [stdout] test encoding::tests::roundtrip_ascii ... ok
[INFO] [stdout] test encoding::tests::roundtrip_invalid_byte ... ok
[INFO] [stdout] test arith::tests::unary ... ok
[INFO] [stdout] test arith::tests::nested_parens ... ok
[INFO] [stdout] test arith::tests::ternary ... ok
[INFO] [stdout] test eval::tests::run_arithmetic_with_variables ... ok
[INFO] [stdout] test encoding::tests::roundtrip_utf8 ... ok
[INFO] [stdout] test encoding::tests::pure_utf8_passthrough ... ok
[INFO] [stdout] test eval::tests::run_case ... ok
[INFO] [stdout] test eval::tests::run_command_substitution ... ok
[INFO] [stdout] test encoding::tests::roundtrip_mixed ... ok
[INFO] [stdout] test eval::tests::run_arithmetic_expansion ... ok
[INFO] [stdout] hello
[INFO] [stdout] test eval::tests::run_command_substitution_backtick ... ok
[INFO] [stdout] test eval::tests::run_and_list ... ok
[INFO] [stdout] test eval::tests::run_nested_command_substitution ... ok
[INFO] [stdout] test arith::tests::assignment ... ok
[INFO] [stdout] test eval::tests::run_exit_status ... ok
[INFO] [stdout] test eval::tests::run_heredoc ... ok
[INFO] [stdout] test eval::tests::run_arithmetic_assignment ... ok
[INFO] [stdout] test eval::tests::run_command_substitution_in_args ... ok
[INFO] [stdout] test eval::tests::run_function ... ok
[INFO] [stdout] test eval::tests::run_for_loop ... ok
[INFO] [stdout] test eval::tests::run_echo ... ok
[INFO] [stdout] test eval::tests::run_local_vars ... ok
[INFO] [stdout] test eval::tests::run_if_true ... ok
[INFO] [stdout] test eval::tests::run_or_list ... ok
[INFO] [stdout] test eval::tests::run_negation ... ok
[INFO] [stdout] test eval::tests::run_set_errexit ... ok
[INFO] [stdout] test eval::tests::run_shift ... ok
[INFO] [stdout] test eval::tests::run_test_file_ops ... ok
[INFO] [stdout] test eval::tests::run_test_builtin ... ok
[INFO] [stdout] test eval::tests::run_test_compound ... ok
[INFO] [stdout] test eval::tests::run_subshell ... ok
[INFO] [stdout] test eval::tests::run_variable_assignment ... ok
[INFO] [stdout] test eval::tests::run_true_false ... ok
[INFO] [stdout] test expand::tests::expand_assign_default ... ok
[INFO] [stdout] test eval::tests::run_variable_expansion ... ok
[INFO] [stdout] test eval::tests::run_while_loop ... ok
[INFO] [stdout] test expand::tests::expand_alternative_set ... ok
[INFO] [stdout] test expand::tests::expand_exit_status ... ok
[INFO] [stdout] test expand::tests::expand_length ... ok
[INFO] [stdout] test expand::tests::expand_default_colon_empty ... ok
[INFO] [stdout] test eval::tests::run_while_with_arithmetic ... ok
[INFO] [stdout] test expand::tests::expand_default_set ... ok
[INFO] [stdout] test expand::tests::expand_alternative_unset ... ok
[INFO] [stdout] test expand::tests::expand_default_unset ... ok
[INFO] [stdout] test eval::tests::run_trap ... ok
[INFO] [stdout] test expand::tests::expand_literal ... 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::field_split_mixed ... ok
[INFO] [stdout] test expand::tests::expand_simple_variable ... ok
[INFO] [stdout] test expand::tests::expand_double_quoted_no_split ... ok
[INFO] [stdout] test expand::tests::expand_single_quoted ... ok
[INFO] [stdout] test eval::tests::run_if_false ... ok
[INFO] [stdout] test expand::tests::expand_tilde_home ... ok
[INFO] [stdout] test expand::tests::expand_trim_prefix_large ... ok
[INFO] [stdout] test expand::tests::expand_trim_prefix_small ... ok
[INFO] [stdout] test glob::tests::fnmatch_bracket_negate ... ok
[INFO] [stdout] test expand::tests::expand_trim_suffix_large ... ok
[INFO] [stdout] test glob::tests::fnmatch_bracket_range ... ok
[INFO] [stdout] test expand::tests::expand_trim_suffix_small ... ok
[INFO] [stdout] test glob::tests::fnmatch_complex ... ok
[INFO] [stdout] test glob::tests::fnmatch_escape ... ok
[INFO] [stdout] test glob::tests::fnmatch_literal ... ok
[INFO] [stdout] test glob::tests::fnmatch_bracket ... ok
[INFO] [stdout] test glob::tests::fnmatch_star ... ok
[INFO] [stdout] test glob::tests::fnmatch_question ... ok
[INFO] [stdout] test glob::tests::has_glob_chars_test ... ok
[INFO] [stdout] test lexer::tests::and_or ... ok
[INFO] [stdout] test lexer::tests::arithmetic ... ok
[INFO] [stdout] test lexer::tests::assignment ... ok
[INFO] [stdout] test lexer::tests::background ... ok
[INFO] [stdout] test lexer::tests::backslash_newline ... ok
[INFO] [stdout] test lexer::tests::command_substitution ... ok
[INFO] [stdout] test lexer::tests::case_tokens ... ok
[INFO] [stdout] test lexer::tests::comments ... ok
[INFO] [stdout] test lexer::tests::pipeline ... ok
[INFO] [stdout] test lexer::tests::redirections ... ok
[INFO] [stdout] test lexer::tests::simple_command ... ok
[INFO] [stdout] test lexer::tests::single_quotes ... ok
[INFO] [stdout] test lexer::tests::double_quotes ... ok
[INFO] [stdout] test lexer::tests::redir_filename ... ok
[INFO] [stdout] test lexer::tests::reserved_words ... ok
[INFO] [stdout] test lexer::tests::unterminated_single_quote ... ok
[INFO] [stdout] test parser::tests::assignment_with_command ... ok
[INFO] [stdout] test parser::tests::background ... ok
[INFO] [stdout] test parser::tests::and_or_list ... ok
[INFO] [stdout] test lexer::tests::dollar_brace ... ok
[INFO] [stdout] test lexer::tests::empty_input ... ok
[INFO] [stdout] test parser::tests::assignment ... ok
[INFO] [stdout] test parser::tests::comments_only ... ok
[INFO] [stdout] test lexer::tests::heredoc_operator ... ok
[INFO] [stdout] test parser::tests::empty_program ... ok
[INFO] [stdout] test parser::tests::brace_group ... ok
[INFO] [stdout] test parser::tests::elif_chain ... ok
[INFO] [stdout] test parser::tests::for_loop_with_words ... ok
[INFO] [stdout] test parser::tests::function_def ... ok
[INFO] [stdout] test parser::tests::case_statement ... ok
[INFO] [stdout] test parser::tests::for_loop_without_words ... ok
[INFO] [stdout] test parser::tests::if_then_else_fi ... ok
[INFO] [stdout] test parser::tests::multiline_program ... ok
[INFO] [stdout] test parser::tests::negated_pipeline ... ok
[INFO] [stdout] test parser::tests::simple_command ... ok
[INFO] [stdout] test parser::tests::if_then_fi ... ok
[INFO] [stdout] test parser::tests::subshell ... ok
[INFO] [stdout] test parser::tests::pipeline ... ok
[INFO] [stdout] test parser::tests::until_loop ... ok
[INFO] [stdout] test parser::tests::redirections ... ok
[INFO] [stdout] test parser::tests::sequence ... ok
[INFO] [stdout] test parser::tests::trim_operations ... ok
[INFO] [stdout] test parser::tests::while_loop ... ok
[INFO] [stdout] test parser::tests::word_parts_arith ... ok
[INFO] [stdout] test parser::tests::word_parts_brace_default ... ok
[INFO] [stdout] test parser::tests::word_parts_cmd_subst ... ok
[INFO] [stdout] test parser::tests::word_parts_length ... ok
[INFO] [stdout] test parser::tests::word_parts_double_quoted_with_var ... ok
[INFO] [stdout] test parser::tests::word_parts_literal ... ok
[INFO] [stdout] test parser::tests::word_parts_single_quoted ... ok
[INFO] [stdout] test parser::tests::word_parts_mixed ... ok
[INFO] [stdout] test parser::tests::word_parts_tilde ... ok
[INFO] [stdout] test parser::tests::word_parts_variable ... ok
[INFO] [stdout] test shell_bytes::tests::shell_roundtrip ... ok
[INFO] [stdout] test shell_bytes::tests::utf8_view ... ok
[INFO] [stdout] test var::tests::ifs_default ... ok
[INFO] [stdout] test var::tests::readonly ... ok
[INFO] [stdout] test var::tests::scope_new_local ... ok
[INFO] [stdout] test var::tests::positional_params ... ok
[INFO] [stdout] test var::tests::scope_local ... ok
[INFO] [stdout] test var::tests::unset ... ok
[INFO] [stdout] test var::tests::set_get ... 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" "758b1557f1aff9c8d516c40b65782e693dd7f53deaf302f9d912ed7978d8533f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "758b1557f1aff9c8d516c40b65782e693dd7f53deaf302f9d912ed7978d8533f", kill_on_drop: false }`
[INFO] [stdout] 758b1557f1aff9c8d516c40b65782e693dd7f53deaf302f9d912ed7978d8533f
