[INFO] fetching crate wasmsh-runtime 0.9.0...
[INFO] testing wasmsh-runtime-0.9.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate wasmsh-runtime 0.9.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate wasmsh-runtime 0.9.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate wasmsh-runtime 0.9.0
[INFO] tweaked toml for crates.io crate wasmsh-runtime 0.9.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate wasmsh-runtime 0.9.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate wasmsh-runtime 0.9.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" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasmsh-ast v0.9.0
[INFO] [stderr]   Downloaded wasmsh-ir v0.9.0
[INFO] [stderr]   Downloaded wasmsh-vm v0.9.0
[INFO] [stderr]   Downloaded wasmsh-hir v0.9.0
[INFO] [stderr]   Downloaded wasmsh-state v0.9.0
[INFO] [stderr]   Downloaded wasmsh-protocol v0.9.0
[INFO] [stderr]   Downloaded hifijson v0.2.3
[INFO] [stderr]   Downloaded posix-regex v0.1.4
[INFO] [stderr]   Downloaded jaq-core v2.2.1
[INFO] [stderr]   Downloaded wasmsh-builtins v0.9.0
[INFO] [stderr]   Downloaded wasmsh-lex v0.9.0
[INFO] [stderr]   Downloaded wasmsh-fs v0.9.0
[INFO] [stderr]   Downloaded ordered-float v5.4.0
[INFO] [stderr]   Downloaded jaq-std v2.1.2
[INFO] [stderr]   Downloaded wasmsh-expand v0.9.0
[INFO] [stderr]   Downloaded jaq-json v1.1.3
[INFO] [stderr]   Downloaded wasmsh-parse v0.9.0
[INFO] [stderr]   Downloaded wasmsh-utils v0.9.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe
[INFO] running `Command { std: "docker" "start" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", 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" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", 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" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling smol_str v0.3.6
[INFO] [stderr]    Compiling crc32fast v1.5.2
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling smallvec v1.16.1
[INFO] [stderr]    Compiling typed-arena v2.0.2
[INFO] [stderr]    Compiling encoding_rs v0.8.41
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling multiversion-macros v0.9.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling yoke-derive v0.8.3
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling urlencoding v2.1.3
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling jaq-core v2.2.1
[INFO] [stderr]    Compiling miniz_oxide v0.9.1
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling zopfli v0.8.3
[INFO] [stderr]    Compiling ordered-float v5.4.0
[INFO] [stderr]    Compiling hifijson v0.2.3
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling sha1 v0.10.7
[INFO] [stderr]    Compiling wasmsh-ast v0.9.0
[INFO] [stderr]    Compiling wasmsh-state v0.9.0
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling saphyr-parser v0.0.6
[INFO] [stderr]    Compiling wasmsh-hir v0.9.0
[INFO] [stderr]    Compiling wasmsh-lex v0.9.0
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling posix-regex v0.1.4
[INFO] [stderr]    Compiling wasmsh-expand v0.9.0
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling flate2 v1.1.10
[INFO] [stderr]    Compiling wasmsh-ir v0.9.0
[INFO] [stderr]    Compiling wasmsh-protocol v0.9.0
[INFO] [stderr]    Compiling wasmsh-parse v0.9.0
[INFO] [stderr]    Compiling jaq-std v2.1.2
[INFO] [stderr]    Compiling multiversion v0.9.0
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling wasmsh-fs v0.9.0
[INFO] [stderr]    Compiling zip v2.4.2
[INFO] [stderr]    Compiling zerovec v0.11.8
[INFO] [stderr]    Compiling zerotrie v0.2.5
[INFO] [stderr]    Compiling wasmsh-builtins v0.9.0
[INFO] [stderr]    Compiling jaq-json v1.1.3
[INFO] [stderr]    Compiling wasmsh-vm v0.9.0
[INFO] [stderr]    Compiling tinystr v0.8.4
[INFO] [stderr]    Compiling potential_utf v0.1.6
[INFO] [stderr]    Compiling icu_collections v2.3.0
[INFO] [stderr]    Compiling icu_locale_core v2.3.0
[INFO] [stderr]    Compiling saphyr v0.0.6
[INFO] [stderr]    Compiling icu_provider v2.3.1
[INFO] [stderr]    Compiling icu_properties v2.3.0
[INFO] [stderr]    Compiling icu_normalizer v2.3.0
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling wasmsh-utils v0.9.0
[INFO] [stderr]    Compiling wasmsh-runtime v0.9.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.53s
[INFO] running `Command { std: "docker" "inspect" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", 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" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling wasmsh-runtime v0.9.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4.22s
[INFO] running `Command { std: "docker" "inspect" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", 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" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/wasmsh_runtime-63c1301dd1acc504)
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test fd_table::tests::command_scoped_redirects_restore_by_cloning_base_table ... ok
[INFO] [stdout] test fd_table::tests::opens_file_for_stdout ... ok
[INFO] [stdout] test fd_table::tests::dup_stderr_to_current_stdout_target ... ok
[INFO] [stdout] test tests::builtin_and_utility_redirections_write_files_during_execution ... ok
[INFO] [stdout] test tests::amp_append_redirection_appends_stdout_and_stderr_for_simple_command ... ok
[INFO] [stdout] test fd_table::tests::pipe_endpoints_can_be_bound_as_fd_targets ... ok
[INFO] [stdout] test tests::exec_live_redirections_preserve_left_to_right_dup_order ... ok
[INFO] [stdout] test tests::command_substitution_isolates_shell_state ... ok
[INFO] [stdout] test tests::live_process_substitution_runner_consumes_before_flush ... ok
[INFO] [stdout] test tests::generic_pipeline_file_capture_preserves_redirection_behavior ... ok
[INFO] [stdout] test tests::live_process_substitution_runner_tee_writes_before_flush ... ok
[INFO] [stdout] test tests::generic_pipeline_capture_does_not_count_hidden_stage_output ... ok
[INFO] [stdout] test tests::output_limit_exposes_structured_exhaustion_reason ... ok
[INFO] [stdout] test tests::pipe_limit_exposes_structured_exhaustion_reason ... ok
[INFO] [stdout] test tests::special_param_underscore_uses_previous_command_last_argument ... ok
[INFO] [stdout] test tests::scheduled_group_stage_pipe_stderr_preserves_output ... ok
[INFO] [stdout] test tests::process_substitution_out_feeds_inner_command ... ok
[INFO] [stdout] test tests::process_substitution_in_registers_live_reader_and_cleans_up ... ok
[INFO] [stdout] test tests::process_substitution_out_runs_live_tail_pipeline ... ok
[INFO] [stdout] test tests::process_substitution_out_runs_live_buffered_pipeline ... ok
[INFO] [stdout] test tests::process_substitution_out_runs_schedulable_inner_pipeline ... ok
[INFO] [stdout] test tests::process_substitution_in_runs_live_buffered_reader_and_cleans_up ... ok
[INFO] [stdout] test tests::recursion_limit_exposes_structured_exhaustion_reason ... ok
[INFO] [stdout] test tests::clobber_redirection_overrides_noclobber ... ok
[INFO] [stdout] test tests::streaming_echo_cut_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_buffered_printf_sort_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_buffered_function_stage_preserves_output ... ok
[INFO] [stdout] test tests::scheduler_single_redirect_only_command_creates_target_file ... ok
[INFO] [stdout] test tests::process_substitution_in_registers_live_sed_reader_and_cleans_up ... ok
[INFO] [stdout] test tests::streaming_echo_column_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_buffered_function_pipe_stderr_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::exec_process_subst_redirections_preserve_left_to_right_dup_order ... ok
[INFO] [stdout] test tests::streaming_tee_pipe_stderr_respects_pipefail ... ok
[INFO] [stdout] test tests::streaming_yes_bat_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_buffered_sort_tee_cat_preserves_sorted_output ... ok
[INFO] [stdout] test tests::streaming_cat_file_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_echo_paste_serial_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_echo_tail_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_yes_cat_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_echo_uniq_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::command_substitution_keeps_inner_stderr_visible ... ok
[INFO] [stdout] test tests::streaming_tee_pipe_stderr_preserves_output_and_stage_status ... ok
[INFO] [stdout] test tests::streaming_yes_tr_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_yes_head_wc_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_yes_grep_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::vm_subset_boundary_accepts_simple_builtin_and_or ... ok
[INFO] [stdout] test tests::streaming_yes_rev_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_yes_sed_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::vm_subset_boundary_rejects_alias_expansion ... ok
[INFO] [stdout] test tests::vm_subset_boundary_rejects_multi_stage_pipeline ... ok
[INFO] [stdout] test tests::streaming_yes_tee_head_respects_visible_output_limit ... ok
[INFO] [stdout] test tests::streaming_yes_head_respects_visible_output_limit ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/runtime_protocol.rs (/opt/rustwide/target/debug/deps/runtime_protocol-11ec5179ccc992ad)
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test cancel_returns_diagnostic ... ok
[INFO] [stdout] test input_redirection_reads_vfs_file_lazily ... ok
[INFO] [stdout] test cancelling_active_execution_returns_clean_exit_and_next_run_recovers ... ok
[INFO] [stdout] test external_handler_2dup1_then_stdout_redirect_keeps_stderr_visible ... ok
[INFO] [stdout] test external_handler_2dup1_into_pipeline_sends_stderr_to_stdout_pipe ... ok
[INFO] [stdout] test compound_array_assignment_preserves_indexed_array_state ... ok
[INFO] [stdout] test parser_lowers_compound_array_assignment_as_assignment_command ... ok
[INFO] [stdout] test parser_preserves_brace_words_as_literals ... ok
[INFO] [stdout] test cat_reads_vfs_file ... ok
[INFO] [stdout] test external_handler_receives_pipeline_stdin ... ok
[INFO] [stdout] test poll_run_drains_incremental_output_until_exit ... ok
[INFO] [stdout] test progressive_cancel_and_restart_work_normally ... ok
[INFO] [stdout] test run_not_initialized_returns_error ... ok
[INFO] [stdout] test list_dir_shows_written_files ... ok
[INFO] [stdout] test read_delimiter_consumes_pipeline_stdin_inside_group ... ok
[INFO] [stdout] test signal_runs_registered_trap_without_exiting_idle_shell ... ok
[INFO] [stdout] test set_x_traces_single_stage_commands ... ok
[INFO] [stdout] test active_execution_yields_and_resumes_with_small_budget ... ok
[INFO] [stdout] test run_recovers_after_exit_builtin ... ok
[INFO] [stdout] test simple_command_runs_with_single_step_budget ... ok
[INFO] [stdout] test external_handler_stdout_redirect_then_2dup1_captures_stderr_in_file ... ok
[INFO] [stdout] test single_quoted_glob_not_expanded ... ok
[INFO] [stdout] test run_echo_hello ... ok
[INFO] [stdout] test start_run_acknowledges_active_execution_without_finishing ... ok
[INFO] [stdout] test single_stage_literal_commands_apply_brace_and_glob_expansion ... ok
[INFO] [stdout] test trapped_signal_can_interrupt_progressive_run_without_forcing_exit ... ok
[INFO] [stdout] test vm_subset_matches_fallback_for_builtin_stdout_redirection ... ok
[INFO] [stdout] test vm_subset_matches_fallback_for_assignment_then_echo ... ok
[INFO] [stdout] test vm_subset_matches_fallback_for_echo_hello ... ok
[INFO] [stdout] test yielded_execution_does_not_leak_unreached_state_into_next_run ... ok
[INFO] [stdout] test run_remains_compatible_with_progressive_protocol_added ... ok
[INFO] [stdout] test write_file_then_read_file ... ok
[INFO] [stdout] test terminating_signal_stops_progressive_run_with_signal_exit_code ... ok
[INFO] [stdout] test invariant_cancellation_isolation_after_pipeline_yield ... ok
[INFO] [stdout] test active_execution_matches_run_wrapper_output ... ok
[INFO] [stdout] test vm_subset_matches_fallback_for_short_circuit_lists ... ok
[INFO] [stdout] test terminating_signal_runs_exit_trap_and_reports_bash_status ... ok
[INFO] [stdout] test trap_inheritance_respects_errtrace_and_functrace_flags ... ok
[INFO] [stdout] test stop_like_signals_report_job_control_gap ... ok
[INFO] [stdout] test invariant_vm_subset_fallback_equivalence_matrix ... ok
[INFO] [stdout] test init_returns_protocol_version ... ok
[INFO] [stderr]      Running tests/shared_io.rs (/opt/rustwide/target/debug/deps/shared_io-e32f459ecee903d2)
[INFO] [stdout] test invariant_yield_resume_equivalence_matrix ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test external_path_uses_same_io_redirection_model ... ok
[INFO] [stdout] test mixed_pipeline_external_and_file_redirection_share_io_model ... ok
[INFO] [stdout] test utility_path_uses_same_io_redirection_model ... ok
[INFO] [stdout] test function_path_uses_same_io_redirection_model ... ok
[INFO] [stdout] test builtin_keyword_bypasses_function_shadowing ... ok
[INFO] [stdout] test function_shadowing_utility_takes_precedence ... ok
[INFO] [stdout] test builtin_path_uses_same_io_redirection_model ... ok
[INFO] [stdout] test nounset_assignment_expansion_surfaces_error_through_vm_subset_path ... ok
[INFO] [stdout] test nounset_builtin_expansion_surfaces_error_through_vm_subset_path ... ok
[INFO] [stdout] test source_uses_redirected_io_and_preserves_shell_state ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests wasmsh_runtime
[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] running `Command { std: "docker" "inspect" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe", kill_on_drop: false }`
[INFO] [stdout] 1e8fed35aa364a2e95c94d13ba51501cf6348086bc55894e5f79073526c36efe
