[INFO] fetching crate patch-rexx 0.9.4...
[INFO] testing patch-rexx-0.9.4 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate patch-rexx 0.9.4 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate patch-rexx 0.9.4
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate patch-rexx 0.9.4
[INFO] tweaked toml for crates.io crate patch-rexx 0.9.4 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate patch-rexx 0.9.4 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate patch-rexx 0.9.4 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded crossterm v0.28.1
[INFO] [stderr]   Downloaded vim-line v4.0.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 00a77258069e109eb3026fb39ba816f7de9868b36acf01719497387c2475fa30
[INFO] running `Command { std: "docker" "start" "-a" "00a77258069e109eb3026fb39ba816f7de9868b36acf01719497387c2475fa30", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "00a77258069e109eb3026fb39ba816f7de9868b36acf01719497387c2475fa30", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "00a77258069e109eb3026fb39ba816f7de9868b36acf01719497387c2475fa30", kill_on_drop: false }`
[INFO] [stdout] 00a77258069e109eb3026fb39ba816f7de9868b36acf01719497387c2475fa30
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2e870866fb1e55004dc28ed80a6f1572135874671658424c6976f7b18689e9cd
[INFO] running `Command { std: "docker" "start" "-a" "2e870866fb1e55004dc28ed80a6f1572135874671658424c6976f7b18689e9cd", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling zerocopy v0.8.40
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling bigdecimal v0.4.10
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling vim-line v4.0.3
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling signal-hook-mio v0.2.5
[INFO] [stderr]    Compiling crossterm v0.28.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling patch-rexx v0.9.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.58s
[INFO] running `Command { std: "docker" "inspect" "2e870866fb1e55004dc28ed80a6f1572135874671658424c6976f7b18689e9cd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2e870866fb1e55004dc28ed80a6f1572135874671658424c6976f7b18689e9cd", kill_on_drop: false }`
[INFO] [stdout] 2e870866fb1e55004dc28ed80a6f1572135874671658424c6976f7b18689e9cd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 70d83e1172c91b72d68c79f075efce3191db4fd0ea025cf0b19c4008fc10bc8c
[INFO] running `Command { std: "docker" "start" "-a" "70d83e1172c91b72d68c79f075efce3191db4fd0ea025cf0b19c4008fc10bc8c", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling patch-rexx v0.9.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.77s
[INFO] running `Command { std: "docker" "inspect" "70d83e1172c91b72d68c79f075efce3191db4fd0ea025cf0b19c4008fc10bc8c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "70d83e1172c91b72d68c79f075efce3191db4fd0ea025cf0b19c4008fc10bc8c", kill_on_drop: false }`
[INFO] [stdout] 70d83e1172c91b72d68c79f075efce3191db4fd0ea025cf0b19c4008fc10bc8c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f6b91130505f24e2fa73c7473d8dddbb4770685732e88a4edacd48e26e26a5bf
[INFO] running `Command { std: "docker" "start" "-a" "f6b91130505f24e2fa73c7473d8dddbb4770685732e88a4edacd48e26e26a5bf", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/patch_rexx-4db2e52e47f62984)
[INFO] [stdout] 
[INFO] [stdout] running 93 tests
[INFO] [stdout] test builtins::tests::test_days_since_base ... ok
[INFO] [stdout] test builtins::tests::test_length ... ok
[INFO] [stdout] test builtins::tests::test_not_a_bif ... ok
[INFO] [stdout] test builtins::tests::test_substr ... ok
[INFO] [stdout] test env::tests::case_insensitive ... ok
[INFO] [stdout] test env::tests::stem_default ... ok
[INFO] [stdout] test builtins::tests::test_words ... ok
[INFO] [stdout] test builtins::tests::test_wrong_arg_count ... ok
[INFO] [stdout] test env::tests::procedure_expose ... ok
[INFO] [stdout] test env::tests::procedure_scope ... ok
[INFO] [stdout] test env::tests::set_and_get ... ok
[INFO] [stdout] test env::tests::stem_variables ... ok
[INFO] [stdout] test env::tests::unset_variable_returns_name ... ok
[INFO] [stdout] test eval::tests::command_handler_with_env_error_trap_fires ... ok
[INFO] [stdout] test env::tests::drop_variable ... ok
[INFO] [stdout] test builtins::tests::test_abs ... ok
[INFO] [stdout] test eval::tests::eval_division ... ok
[INFO] [stdout] test eval::tests::eval_division_by_zero ... ok
[INFO] [stdout] test eval::tests::eval_power ... ok
[INFO] [stdout] test eval::tests::eval_comparison_equal ... ok
[INFO] [stdout] test eval::tests::eval_comparison_numeric ... ok
[INFO] [stdout] test eval::tests::eval_precedence ... ok
[INFO] [stdout] test eval::tests::eval_comparison_string ... ok
[INFO] [stdout] test eval::tests::eval_multiplication ... ok
[INFO] [stdout] test eval::tests::eval_integer_division ... ok
[INFO] [stdout] test eval::tests::eval_remainder ... ok
[INFO] [stdout] test eval::tests::eval_logical_and ... ok
[INFO] [stdout] test eval::tests::eval_strict_comparison ... ok
[INFO] [stdout] test eval::tests::eval_logical_not ... ok
[INFO] [stdout] test eval::tests::eval_say_runs ... ok
[INFO] [stdout] test eval::tests::eval_logical_or ... ok
[INFO] [stdout] test eval::tests::command_handler_with_env_some_skips_basic ... ok
[INFO] [stdout] test eval::tests::eval_string_concat_abuttal ... ok
[INFO] [stdout] test eval::tests::eval_addition ... ok
[INFO] [stdout] test eval::tests::eval_string_concat_blank ... ok
[INFO] [stdout] test eval::tests::command_handler_with_env_sets_compound ... ok
[INFO] [stdout] test eval::tests::eval_subtraction ... ok
[INFO] [stdout] test lexer::tests::arithmetic_tokens ... ok
[INFO] [stdout] test eval::tests::eval_unset_variable_returns_name ... ok
[INFO] [stdout] test lexer::tests::simple_say ... ok
[INFO] [stdout] test parser::tests::parse_assignment_clause ... ok
[INFO] [stdout] test parser::tests::parse_concat_forms ... ok
[INFO] [stdout] test lexer::tests::doubled_quote_escape ... ok
[INFO] [stdout] test parser::tests::parse_addition ... ok
[INFO] [stdout] test eval::tests::command_handler_with_env_none_falls_through_to_basic ... ok
[INFO] [stdout] test parser::tests::parse_controlled_do ... ok
[INFO] [stdout] test lexer::tests::nested_comments ... ok
[INFO] [stdout] test lexer::tests::hex_string ... ok
[INFO] [stdout] test lexer::tests::shebang_line_skipped ... ok
[INFO] [stdout] test lexer::tests::comparison_operators ... ok
[INFO] [stdout] test parser::tests::parse_comparison ... ok
[INFO] [stdout] test parser::tests::parse_do_count ... ok
[INFO] [stdout] test parser::tests::parse_exit ... ok
[INFO] [stdout] test parser::tests::parse_exit_with_expr ... ok
[INFO] [stdout] test parser::tests::parse_explicit_concat ... ok
[INFO] [stdout] test parser::tests::parse_if_then_else ... ok
[INFO] [stdout] test parser::tests::parse_label ... ok
[INFO] [stdout] test parser::tests::parse_leave ... ok
[INFO] [stdout] test parser::tests::parse_precedence ... ok
[INFO] [stdout] test parser::tests::parse_string_literal ... ok
[INFO] [stdout] test parser::tests::parse_unary_minus ... ok
[INFO] [stdout] test parser::tests::parse_select ... ok
[INFO] [stdout] test parser::tests::parse_if_then ... ok
[INFO] [stdout] test parser::tests::parse_multiple_clauses ... ok
[INFO] [stdout] test parser::tests::parse_say_clause ... ok
[INFO] [stdout] test parser::tests::parse_simple_do ... ok
[INFO] [stdout] test eval::tests::eval_unary_minus ... ok
[INFO] [stdout] test parser::tests::parse_number_literal ... ok
[INFO] [stdout] test parser::tests::parse_parens ... ok
[INFO] [stdout] test parser::tests::parse_power_right_assoc ... ok
[INFO] [stdout] test parser::tests::parse_function_call ... ok
[INFO] [stdout] test value::tests::format_large_plain ... ok
[INFO] [stdout] test value::tests::format_negative ... ok
[INFO] [stdout] test value::tests::format_negative_exponential ... ok
[INFO] [stdout] test value::tests::format_significant_digit_rounding ... ok
[INFO] [stdout] test value::tests::format_trailing_zeros_stripped ... ok
[INFO] [stdout] test value::tests::format_small_exponential ... ok
[INFO] [stdout] test value::tests::format_zero ... ok
[INFO] [stdout] test value::tests::format_decimal ... ok
[INFO] [stdout] test value::tests::format_engineering_form ... ok
[INFO] [stdout] test value::tests::format_small_plain ... ok
[INFO] [stdout] test value::tests::format_integer ... ok
[INFO] [stdout] test eval::tests::eval_negative_power ... ok
[INFO] [stdout] test value::tests::from_integer ... ok
[INFO] [stdout] test value::tests::numeric_with_spaces ... ok
[INFO] [stdout] test value::tests::string_value ... ok
[INFO] [stdout] test parser::tests::parse_unmatched_paren_error ... ok
[INFO] [stdout] test value::tests::default_numeric_settings ... ok
[INFO] [stdout] test value::tests::format_large_exponential ... ok
[INFO] [stderr] sh: 1: 43: not found
[INFO] [stdout] test eval::tests::eval_variable_assignment_and_lookup ... ok
[INFO] [stdout] test value::tests::non_numeric ... ok
[INFO] [stdout] test value::tests::numeric_value ... ok
[INFO] [stdout] test eval::tests::command_handler_with_env_failure_trap_fires_on_negative_rc ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 93 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rexx-24d7362a6dcf8fbe)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test repl::tests::convert_ctrl_modifier ... ok
[INFO] [stdout] test repl::tests::convert_plain_char ... ok
[INFO] [stdout] test repl::tests::convert_unknown_key_returns_escape ... ok
[INFO] [stdout] test repl::tests::convert_special_keys ... ok
[INFO] [stdout] test repl::tests::history_add_and_navigate ... ok
[INFO] [stdout] test repl::tests::history_empty_navigation ... ok
[INFO] [stdout] test repl::tests::history_reset_position ... ok
[INFO] [stdout] test repl::tests::history_saves_current_input ... ok
[INFO] [stderr]      Running tests/conformance.rs (/opt/rustwide/target/debug/deps/conformance-f62e254c420a24fb)
[INFO] [stdout] test repl::tests::history_save_load_roundtrip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 75 tests
[INFO] [stdout] test sec10_signal_exits_triple_nested ... ok
[INFO] [stdout] test sec11_left_with_custom_pad ... ok
[INFO] [stdout] test sec11_datatype_whole_number ... ok
[INFO] [stdout] test sec11_datatype_decimal_not_whole ... ok
[INFO] [stdout] test sec10_condition_description_nonempty ... ok
[INFO] [stdout] test sec10_novalue_in_concat_expr ... ok
[INFO] [stdout] test sec10_trap_reenable_different_handler ... ok
[INFO] [stdout] test sec11_compare_equal_padded ... ok
[INFO] [stdout] test sec10_signal_value_computed ... ok
[INFO] [stdout] test sec11_datatype_alpha_empty ... ok
[INFO] [stdout] test sec11_left_zero_length ... ok
[INFO] [stdout] test sec10_signal_off_then_on ... ok
[INFO] [stdout] test sec6_binary_string_say ... ok
[INFO] [stdout] test sec11_substr_custom_pad ... ok
[INFO] [stdout] test sec11_translate_incomplete_output ... ok
[INFO] [stdout] test sec11_wordpos_phrase_past_end ... ok
[INFO] [stdout] test sec11_substr_start_past_end ... ok
[INFO] [stdout] test sec6_doubled_quote_double_quotes ... ok
[INFO] [stdout] test sec6_empty_program ... ok
[INFO] [stdout] test sec6_hex_string_say ... ok
[INFO] [stdout] test sec11_translate_with_pad_char ... ok
[INFO] [stdout] test sec6_hex_string_empty ... ok
[INFO] [stdout] test sec11_datatype_empty_string ... ok
[INFO] [stdout] test sec11_strip_leading_zeros ... ok
[INFO] [stdout] test sec6_nested_comments_deep ... ok
[INFO] [stdout] test sec7_blank_concat_vs_abuttal ... ok
[INFO] [stdout] test sec7_compound_variable_default_name ... ok
[INFO] [stdout] test sec7_multiple_empty_clauses ... ok
[INFO] [stdout] test sec7_nop_in_if ... ok
[INFO] [stdout] test sec7_nop_in_select_otherwise ... ok
[INFO] [stdout] test sec6_hex_string_with_spaces ... ok
[INFO] [stdout] test sec11_right_zero_length ... ok
[INFO] [stdout] test sec7_stem_default_assignment ... ok
[INFO] [stdout] test sec8_division_precision ... ok
[INFO] [stdout] test sec8_intdiv_negative ... ok
[INFO] [stdout] test sec8_logical_non_boolean_error ... ok
[INFO] [stdout] test sec8_logical_and ... ok
[INFO] [stdout] test sec7_compound_variable_complex_tail ... ok
[INFO] [stdout] test sec6_comment_only_program ... ok
[INFO] [stdout] test sec11_substr_beyond_end_pads ... ok
[INFO] [stdout] test sec7_say_no_expression ... ok
[INFO] [stdout] test sec6_line_comment_after_code ... ok
[INFO] [stdout] test sec10_signal_from_otherwise ... ok
[INFO] [stdout] test sec6_mixed_quote_styles ... ok
[INFO] [stdout] test sec7_abuttal_variable_string ... ok
[INFO] [stdout] test sec8_normal_eq_pads_shorter ... ok
[INFO] [stdout] test sec11_verify_match_mode ... ok
[INFO] [stdout] test sec8_logical_xor_different ... ok
[INFO] [stdout] test sec11_format_integer_width ... ok
[INFO] [stdout] test sec11_format_before_overflow ... ok
[INFO] [stdout] test sec11_right_with_custom_pad ... ok
[INFO] [stdout] test sec8_logical_not_one ... ok
[INFO] [stdout] test sec8_normal_gt_numeric_order ... ok
[INFO] [stdout] test sec8_logical_or ... ok
[INFO] [stdout] test sec8_logical_xor_same ... ok
[INFO] [stdout] test sec7_function_call_space_before_paren ... ok
[INFO] [stdout] test sec8_numeric_cmp_leading_zeros ... ok
[INFO] [stdout] test sec11_verify_nomatch_with_start ... ok
[INFO] [stdout] test sec8_power_right_assoc_execution ... ok
[INFO] [stdout] test sec9_parse_pattern_not_found ... ok
[INFO] [stdout] test sec9_parse_multiple_literal_patterns ... ok
[INFO] [stdout] test sec9_parse_relative_zero ... ok
[INFO] [stdout] test sec8_numeric_cmp_trailing_zeros ... ok
[INFO] [stdout] test sec9_parse_comma_separates_args ... ok
[INFO] [stdout] test sec8_strict_eq_trailing_space ... ok
[INFO] [stdout] test sec9_parse_value_computed ... ok
[INFO] [stdout] test sec8_logical_not_zero ... ok
[INFO] [stdout] test sec8_string_cmp_non_numeric ... ok
[INFO] [stdout] test sec9_parse_upper_vs_parse ... ok
[INFO] [stdout] test sec9_parse_three_comma_args ... ok
[INFO] [stdout] test sec8_strict_eq_leading_space ... ok
[INFO] [stdout] test sec8_remainder_negative ... ok
[INFO] [stdout] test sec9_parse_backward_reposition ... ok
[INFO] [stdout] test sec8_numeric_cmp_whitespace ... ok
[INFO] [stdout] test sec8_strict_gt_string_order ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 75 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/phase1.rs (/opt/rustwide/target/debug/deps/phase1-cbe559461df3ff64)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test say_multiple_statements ... ok
[INFO] [stdout] test say_variable ... ok
[INFO] [stdout] test say_precedence ... ok
[INFO] [stdout] test say_comparison ... ok
[INFO] [stdout] test say_string_literal ... ok
[INFO] [stdout] test say_blank_concat ... ok
[INFO] [stdout] test say_addition ... ok
[INFO] [stdout] test say_power ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[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/phase10.rs (/opt/rustwide/target/debug/deps/phase10-a70d033543f2f00d)
[INFO] [stderr]      Running tests/phase11.rs (/opt/rustwide/target/debug/deps/phase11-a9ca9f264172b92c)
[INFO] [stdout] 
[INFO] [stdout] running 18 tests
[INFO] [stdout] test case_insensitive_resolution ... ok
[INFO] [stdout] test bif_priority ... ok
[INFO] [stdout] test external_calls_external ... ok
[INFO] [stdout] test external_no_return_call ... ok
[INFO] [stdout] test external_not_found ... ok
[INFO] [stdout] test external_call_basic ... ok
[INFO] [stdout] test external_parse_error ... ok
[INFO] [stdout] test external_no_return_expr ... ok
[INFO] [stdout] test external_function_in_expr ... ok
[INFO] [stdout] test source_path_restored_after_error ... ok
[INFO] [stdout] test external_scope_isolation ... ok
[INFO] [stdout] test external_calls_external_subdir ... ok
[INFO] [stdout] test rexxpath_resolution ... ok
[INFO] [stdout] test external_from_eval_mode ... ok
[INFO] [stdout] test internal_label_priority ... ok
[INFO] [stdout] test rexxpath_multiple_entries ... ok
[INFO] [stdout] test rex_extension ... ok
[INFO] [stdout] test parse_source_filename ... ok
[INFO] [stderr]      Running tests/phase12a.rs (/opt/rustwide/target/debug/deps/phase12a-1e76c4de88125328)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 30 tests
[INFO] [stdout] test line_continuation_in_expr ... ok
[INFO] [stdout] test line_continuation_multiple ... ok
[INFO] [stdout] test line_continuation_with_comment ... ok
[INFO] [stdout] test mixed_push_queue_order ... ok
[INFO] [stdout] test numeric_digits_too_large ... ok
[INFO] [stdout] test numeric_digits_high_precision ... ok
[INFO] [stdout] test comma_in_function_not_continuation ... ok
[INFO] [stdout] test numeric_digits_basic ... ok
[INFO] [stdout] test numeric_digits_reset_default ... ok
[INFO] [stdout] test numeric_digits_affects_arithmetic ... ok
[INFO] [stdout] test numeric_form_default ... ok
[INFO] [stdout] test numeric_digits_default ... ok
[INFO] [stdout] test numeric_form_value_expr ... ok
[INFO] [stdout] test pull_shorthand_from_queue ... ok
[INFO] [stdout] test numeric_fuzz_basic ... ok
[INFO] [stdout] test numeric_fuzz_must_be_less_than_digits ... ok
[INFO] [stdout] test queued_bif_decreases_after_pull ... ok
[INFO] [stdout] test parse_pull_from_queue ... ok
[INFO] [stdout] test numeric_fuzz_default ... ok
[INFO] [stdout] test numeric_form_scientific ... ok
[INFO] [stdout] test queue_fifo_order ... ok
[INFO] [stdout] test queue_and_pull ... ok
[INFO] [stdout] test push_empty_line ... ok
[INFO] [stdout] test queued_bif_after_push ... ok
[INFO] [stdout] test numeric_form_engineering ... ok
[INFO] [stdout] test push_lifo_order ... ok
[INFO] [stdout] test push_and_pull ... ok
[INFO] [stdout] test queued_bif_empty ... ok
[INFO] [stdout] test numeric_fuzz_too_large ... ok
[INFO] [stdout] test line_continuation_basic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/phase2.rs (/opt/rustwide/target/debug/deps/phase2-a3059260368ead43)
[INFO] [stdout] 
[INFO] [stdout] running 36 tests
[INFO] [stdout] test controlled_do_for ... ok
[INFO] [stdout] test controlled_do_until_final_value ... ok
[INFO] [stdout] test duplicate_by_keyword_error ... ok
[INFO] [stdout] test exit_stops_execution ... ok
[INFO] [stdout] test do_forever_leave ... ok
[INFO] [stdout] test do_simple_block ... ok
[INFO] [stdout] test do_while ... ok
[INFO] [stdout] test controlled_do_basic ... ok
[INFO] [stdout] test do_until ... ok
[INFO] [stdout] test do_count ... ok
[INFO] [stdout] test controlled_do_print_values ... ok
[INFO] [stdout] test controlled_do_to_final_value ... ok
[INFO] [stdout] test fractional_loop_count_error ... ok
[INFO] [stdout] test duplicate_to_keyword_error ... ok
[INFO] [stdout] test if_else_across_lines ... ok
[INFO] [stdout] test factorial ... ok
[INFO] [stdout] test large_exponent_error ... ok
[INFO] [stdout] test iterate_in_loop ... ok
[INFO] [stdout] test if_comparison ... ok
[INFO] [stdout] test if_then_across_lines ... ok
[INFO] [stdout] test iterate_in_do_until ... ok
[INFO] [stdout] test if_then_true ... ok
[INFO] [stdout] test if_then_false ... ok
[INFO] [stdout] test negative_by_step ... ok
[INFO] [stdout] test if_then_else ... ok
[INFO] [stdout] test leave_outside_loop ... ok
[INFO] [stdout] test zero_by_step_error ... ok
[INFO] [stdout] test nested_do ... ok
[INFO] [stdout] test select_otherwise ... ok
[INFO] [stdout] test select_when ... ok
[INFO] [stdout] test stray_end_error ... ok
[INFO] [stdout] test fizzbuzz ... ok
[INFO] [stdout] test select_no_match_no_otherwise ... ok
[INFO] [stdout] test stray_then_error ... ok
[INFO] [stdout] test negative_loop_count_error ... ok
[INFO] [stdout] test controlled_do_by ... ok
[INFO] [stderr]      Running tests/phase3.rs (/opt/rustwide/target/debug/deps/phase3-d31fc002c09985f1)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 36 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test call_basic_subroutine ... ok
[INFO] [stdout] test multiple_arguments ... ok
[INFO] [stdout] test error_function_no_return_data ... ok
[INFO] [stdout] test error_misplaced_procedure ... ok
[INFO] [stdout] test call_with_result ... ok
[INFO] [stdout] test error_routine_not_found ... ok
[INFO] [stdout] test arg_uppercases ... ok
[INFO] [stdout] test call_no_args ... ok
[INFO] [stdout] test function_call_in_expression ... ok
[INFO] [stdout] test recursive_factorial ... ok
[INFO] [stdout] test procedure_expose ... ok
[INFO] [stdout] test procedure_isolation ... ok
[INFO] [stdout] test nested_calls ... ok
[INFO] [stdout] test drop_instruction ... ok
[INFO] [stdout] test result_dropped_when_no_return_value ... ok
[INFO] [stdout] test exit_prevents_fall_through ... ok
[INFO] [stdout] test recursive_fibonacci ... ok
[INFO] [stderr]      Running tests/phase4.rs (/opt/rustwide/target/debug/deps/phase4-7916fc7a99a9a091)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test arg_missing_returns_empty ... ok
[INFO] [stdout] test error_template_paren_missing_close ... ok
[INFO] [stdout] test arg_comma_multiple_args ... ok
[INFO] [stdout] test parse_value_with_expression ... ok
[INFO] [stdout] test parse_var_absolute_position ... ok
[INFO] [stdout] test parse_linein_reads_stdin ... ok
[INFO] [stdout] test parse_var_backward_reposition ... ok
[INFO] [stdout] test parse_var_empty_source ... ok
[INFO] [stdout] test parse_var_dot_placeholder ... ok
[INFO] [stdout] test backward_compat_add ... ok
[INFO] [stdout] test parse_var_excess_vars_get_empty ... ok
[INFO] [stdout] test parse_var_literal_multi_char_pattern ... ok
[INFO] [stdout] test parse_var_literal_not_found ... ok
[INFO] [stdout] test parse_value_with_simple ... ok
[INFO] [stdout] test parse_upper_value ... ok
[INFO] [stdout] test error_template_minus_without_number ... ok
[INFO] [stdout] test parse_source ... ok
[INFO] [stdout] test error_parse_var_missing_name ... ok
[INFO] [stdout] test parse_upper_var ... ok
[INFO] [stdout] test parse_var_multibyte_absolute_position ... ok
[INFO] [stdout] test parse_var_multibyte_relative_position ... ok
[INFO] [stdout] test parse_var_multibyte_word_split ... ok
[INFO] [stdout] test arg_word_split_in_subroutine ... ok
[INFO] [stdout] test arg_literal_pattern_in_subroutine ... ok
[INFO] [stdout] test parse_arg_preserves_case ... ok
[INFO] [stdout] test parse_var_empty_variable_pattern ... ok
[INFO] [stdout] test backward_compat_factorial ... ok
[INFO] [stdout] test error_parse_value_missing_with ... ok
[INFO] [stdout] test error_template_plus_without_number ... ok
[INFO] [stdout] test error_parse_invalid_subkeyword ... ok
[INFO] [stdout] test parse_var_literal_slash_delimited ... ok
[INFO] [stdout] test parse_var_single_word_multiple_targets ... ok
[INFO] [stdout] test parse_var_single_var_gets_all ... ok
[INFO] [stdout] test pull_uppercases_stdin ... ok
[INFO] [stdout] test parse_var_relative_forward ... ok
[INFO] [stdout] test parse_var_three_vars_last_gets_rest ... ok
[INFO] [stdout] test parse_var_no_template ... ok
[INFO] [stdout] test pull_with_template ... ok
[INFO] [stdout] test parse_var_two_vars_word_split ... ok
[INFO] [stdout] test parse_var_tab_as_blank ... ok
[INFO] [stdout] test parse_version ... ok
[INFO] [stdout] test parse_var_variable_pattern ... ok
[INFO] [stderr]      Running tests/phase5.rs (/opt/rustwide/target/debug/deps/phase5-fd8eebc8a6e5ffc3)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 92 tests
[INFO] [stdout] test bif_abbrev_false ... ok
[INFO] [stdout] test bif_compare_equal ... ok
[INFO] [stdout] test bif_c2x_basic ... ok
[INFO] [stdout] test bif_abbrev_true ... ok
[INFO] [stdout] test bif_c2d_basic ... ok
[INFO] [stdout] test bif_changestr_basic ... ok
[INFO] [stdout] test bif_compare_different ... ok
[INFO] [stdout] test bif_copies_basic ... ok
[INFO] [stdout] test bif_d2c_basic ... ok
[INFO] [stdout] test bif_d2x_with_length ... ok
[INFO] [stdout] test bif_d2x_basic ... ok
[INFO] [stdout] test bif_b2x_basic ... ok
[INFO] [stdout] test bif_abs_positive ... ok
[INFO] [stdout] test bif_countstr_basic ... ok
[INFO] [stdout] test bif_datatype_hex_check ... ok
[INFO] [stdout] test bif_datatype_num ... ok
[INFO] [stdout] test bif_datatype_char ... ok
[INFO] [stdout] test bif_date_days_of_year ... ok
[INFO] [stdout] test bif_datatype_num_check ... ok
[INFO] [stdout] test bif_delstr_to_end ... ok
[INFO] [stdout] test bif_delstr_basic ... ok
[INFO] [stdout] test bif_date_sorted ... ok
[INFO] [stdout] test bif_format_basic ... ok
[INFO] [stdout] test bif_date_normal ... ok
[INFO] [stdout] test bif_delword_to_end ... ok
[INFO] [stdout] test bif_datatype_type_check ... ok
[INFO] [stdout] test bif_date_weekday ... ok
[INFO] [stdout] test bif_length_empty ... ok
[INFO] [stdout] test bif_max_basic ... ok
[INFO] [stdout] test bif_left_with_pad ... ok
[INFO] [stdout] test bif_pos_with_start ... ok
[INFO] [stdout] test bif_random_default ... ok
[INFO] [stdout] test bif_random_range ... ok
[INFO] [stdout] test bif_right_basic ... ok
[INFO] [stdout] test bif_reverse_basic ... ok
[INFO] [stdout] test bif_right_with_pad ... ok
[INFO] [stdout] test bif_routine_not_found ... ok
[INFO] [stdout] test bif_sign_positive ... ok
[INFO] [stdout] test bif_sign_negative ... ok
[INFO] [stdout] test bif_delword_basic ... ok
[INFO] [stdout] test bif_space_basic ... ok
[INFO] [stdout] test bif_sign_zero ... ok
[INFO] [stdout] test bif_index_basic ... ok
[INFO] [stdout] test bif_left_basic ... ok
[INFO] [stdout] test bif_strip_both ... ok
[INFO] [stdout] test bif_strip_custom_char ... ok
[INFO] [stdout] test bif_length_basic ... ok
[INFO] [stdout] test bif_pos_basic ... ok
[INFO] [stdout] test bif_insert_basic ... ok
[INFO] [stdout] test bif_substr_with_length ... ok
[INFO] [stdout] test bif_subword_to_end ... ok
[INFO] [stdout] test bif_substr_basic ... ok
[INFO] [stdout] test bif_subword_basic ... ok
[INFO] [stdout] test bif_substr_with_pad ... ok
[INFO] [stdout] test bif_time_hours ... ok
[INFO] [stdout] test bif_time_normal ... ok
[INFO] [stdout] test bif_translate_with_tables ... ok
[INFO] [stdout] test bif_translate_uppercase ... ok
[INFO] [stdout] test bif_space_with_n ... ok
[INFO] [stdout] test bif_verify_all_match ... ok
[INFO] [stdout] test bif_trunc_with_decimals ... ok
[INFO] [stdout] test bif_verify_basic ... ok
[INFO] [stdout] test bif_word_basic ... ok
[INFO] [stdout] test bif_wordindex_basic ... ok
[INFO] [stdout] test bif_wordlength_basic ... ok
[INFO] [stdout] test bif_wordpos_not_found ... ok
[INFO] [stdout] test bif_words_basic ... ok
[INFO] [stdout] test bif_word_out_of_range ... ok
[INFO] [stdout] test bif_words_empty ... ok
[INFO] [stdout] test bif_wordpos_basic ... ok
[INFO] [stdout] test bif_wrong_arg_count ... ok
[INFO] [stdout] test bif_time_seconds ... ok
[INFO] [stdout] test bif_x2b_basic ... ok
[INFO] [stdout] test bif_x2d_basic ... ok
[INFO] [stdout] test bif_x2c_basic ... ok
[INFO] [stdout] test bif_x2d_signed ... ok
[INFO] [stdout] test bif_xrange_basic ... ok
[INFO] [stdout] test call_bif_sets_result ... ok
[INFO] [stdout] test internal_label_overrides_bif ... ok
[INFO] [stdout] test bif_abbrev_min_length ... ok
[INFO] [stdout] test bif_copies_zero ... ok
[INFO] [stdout] test bif_strip_trailing ... ok
[INFO] [stdout] test bif_overlay_basic ... ok
[INFO] [stdout] test bif_space_zero ... ok
[INFO] [stdout] test bif_pos_not_found ... ok
[INFO] [stdout] test bif_lastpos_basic ... ok
[INFO] [stdout] test bif_abs_negative ... ok
[INFO] [stdout] test bif_non_numeric_arg ... ok
[INFO] [stdout] test bif_trunc_basic ... ok
[INFO] [stdout] test bif_min_basic ... ok
[INFO] [stdout] test bif_strip_leading ... ok
[INFO] [stdout] test call_bif_abs_sets_result ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 92 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.28s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/phase6.rs (/opt/rustwide/target/debug/deps/phase6-d6de2b3e326a2859)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test condition_bif_no_trap_returns_empty ... ok
[INFO] [stdout] test rc_accessible_after_syntax_trap ... ok
[INFO] [stdout] test novalue_from_function_call ... ok
[INFO] [stdout] test novalue_trap_fires_once ... ok
[INFO] [stdout] test novalue_condition_info ... ok
[INFO] [stdout] test signal_case_insensitive_label ... ok
[INFO] [stdout] test signal_off_novalue_disables ... ok
[INFO] [stdout] test signal_nonexistent_label_error_16 ... ok
[INFO] [stdout] test signal_off_without_on_is_noop ... ok
[INFO] [stdout] test signal_on_syntax_fires ... ok
[INFO] [stdout] test novalue_set_variable_does_not_fire ... ok
[INFO] [stdout] test signal_basic_jump ... ok
[INFO] [stdout] test signal_exits_deeply_nested ... ok
[INFO] [stdout] test novalue_fires_before_arithmetic_error ... ok
[INFO] [stdout] test signal_value_expression ... ok
[INFO] [stdout] test signal_on_syntax_sets_rc ... ok
[INFO] [stdout] test signal_on_syntax_with_name ... ok
[INFO] [stdout] test trap_reenable_after_autodisable ... ok
[INFO] [stdout] test signal_value_basic ... ok
[INFO] [stdout] test signal_value_nonexistent_label_error_16 ... ok
[INFO] [stdout] test signal_skips_intermediate_code ... ok
[INFO] [stdout] test signal_bad_condition_error_25 ... ok
[INFO] [stdout] test signal_from_select_when ... ok
[INFO] [stdout] test signal_on_novalue_fires ... ok
[INFO] [stdout] test condition_bif_instruction ... ok
[INFO] [stdout] test novalue_condition_description ... ok
[INFO] [stdout] test signal_exits_nested_do ... ok
[INFO] [stdout] test condition_bif_no_arg_returns_instruction ... ok
[INFO] [stdout] test novalue_compound_variable ... ok
[INFO] [stdout] test condition_bif_status ... ok
[INFO] [stdout] test novalue_compound_description ... ok
[INFO] [stdout] test signal_on_novalue_with_name ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/phase7.rs (/opt/rustwide/target/debug/deps/phase7-70ee2749b779112a)
[INFO] [stdout] 
[INFO] [stdout] running 29 tests
[INFO] [stdout] test interpret_constructed_expression ... ok
[INFO] [stdout] test interpret_assignment_only ... ok
[INFO] [stdout] test interpret_if_then_else ... ok
[INFO] [stdout] test interpret_bare_keyword ... ok
[INFO] [stdout] test interpret_depth_limit ... ok
[INFO] [stdout] test interpret_inherits_traps ... ok
[INFO] [stdout] test interpret_expression ... ok
[INFO] [stdout] test interpret_exit_propagates ... ok
[INFO] [stdout] test interpret_empty_string ... ok
[INFO] [stdout] test interpret_can_set_traps ... ok
[INFO] [stdout] test interpret_do_loop ... ok
[INFO] [stdout] test interpret_leave_propagates ... ok
[INFO] [stdout] test interpret_local_signal ... ok
[INFO] [stdout] test interpret_dynamic_instruction ... ok
[INFO] [stdout] test interpret_leave_named_do ... ok
[INFO] [stdout] test interpret_local_label_priority ... ok
[INFO] [stdout] test interpret_modifies_caller_variable ... ok
[INFO] [stdout] test interpret_signal_to_trailing_label ... ok
[INFO] [stdout] test interpret_runtime_error_fires_syntax_trap ... ok
[INFO] [stdout] test interpret_reads_caller_variable ... ok
[INFO] [stdout] test interpret_simple_say ... ok
[INFO] [stdout] test interpret_parse_error_no_trap ... ok
[INFO] [stdout] test interpret_nested ... ok
[INFO] [stdout] test interpret_new_variable_visible_after ... ok
[INFO] [stdout] test interpret_iterate_propagates ... ok
[INFO] [stdout] test interpret_signal_propagates_out ... ok
[INFO] [stdout] test interpret_parse_error_fires_syntax_trap ... ok
[INFO] [stdout] test interpret_multiple_clauses ... ok
[INFO] [stdout] test interpret_select ... ok
[INFO] [stderr]      Running tests/phase8.rs (/opt/rustwide/target/debug/deps/phase8-d993d7eb0c8ed0a3)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 28 tests
[INFO] [stdout] test address_default_is_system ... ok
[INFO] [stdout] test address_bif_no_args ... ok
[INFO] [stdout] test address_case_insensitive ... ok
[INFO] [stdout] test address_value ... ok
[INFO] [stdout] test address_temporary ... ok
[INFO] [stdout] test command_in_do_loop ... ok
[INFO] [stdout] test address_set_environment ... ok
[INFO] [stdout] test address_in_subroutine ... ok
[INFO] [stdout] test address_swap_double ... ok
[INFO] [stdout] test address_value_expression ... ok
[INFO] [stdout] test address_swap ... ok
[INFO] [stdout] test address_temporary_reverts_after_error ... ok
[INFO] [stdout] test address_temporary_preserves_rc ... ok
[INFO] [stdout] test command_simple ... ok
[INFO] [stdout] test command_rc_zero ... ok
[INFO] [stdout] test command_variable ... ok
[INFO] [stdout] test command_in_interpret ... ok
[INFO] [stdout] test command_rc_persists_across_say ... ok
[INFO] [stdout] test command_expression ... ok
[INFO] [stdout] test command_preserves_rc ... ok
[INFO] [stdout] test error_no_trap_continues ... ok
[INFO] [stdout] test error_condition_info ... ok
[INFO] [stdout] test command_with_signal_on_syntax ... ok
[INFO] [stdout] test failure_trap_fires ... ok
[INFO] [stdout] test error_trap_sets_rc ... ok
[INFO] [stdout] test error_trap_fires ... ok
[INFO] [stdout] test command_rc_nonzero ... ok
[INFO] [stdout] test error_trap_fires_once ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 28 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/phase9.rs (/opt/rustwide/target/debug/deps/phase9-ec1520beca6cb5f8)
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test adherence_all_traces_labels_and_clauses ... ok
[INFO] [stdout] test adherence_trace_bif_toggle_off ... ok
[INFO] [stdout] test adherence_commands_does_not_trace_labels ... ok
[INFO] [stdout] test adherence_commands_traces_commands_not_say ... ok
[INFO] [stdout] test adherence_intermediates_does_not_trace_labels ... ok
[INFO] [stdout] test adherence_labels_level_traces_only_labels ... ok
[INFO] [stdout] test adherence_results_does_not_trace_labels ... ok
[INFO] [stdout] test trace_bif_interactive_flag ... ok
[INFO] [stdout] test trace_bif_default_is_normal ... ok
[INFO] [stdout] test trace_case_insensitive ... ok
[INFO] [stdout] test trace_bif_returns_previous ... ok
[INFO] [stdout] test trace_bif_toggle_interactive ... ok
[INFO] [stdout] test trace_bif_roundtrip ... ok
[INFO] [stdout] test trace_literal_tag ... ok
[INFO] [stdout] test trace_off_no_output ... ok
[INFO] [stdout] test trace_off_suppresses_all ... ok
[INFO] [stdout] test trace_intermediates_shows_tags ... ok
[INFO] [stdout] test trace_bif_sets_new ... ok
[INFO] [stdout] test trace_function_tag ... ok
[INFO] [stdout] test trace_in_do_loop ... ok
[INFO] [stdout] test trace_bif_returns_current ... ok
[INFO] [stdout] test trace_all_traces_labels ... ok
[INFO] [stdout] test trace_compound_via_symbol ... ok
[INFO] [stdout] test trace_invalid_errors ... ok
[INFO] [stdout] test trace_instruction_change_midstream ... ok
[INFO] [stdout] test adherence_trace_bif_toggle_only ... ok
[INFO] [stdout] test trace_commands_shows_command ... ok
[INFO] [stdout] test trace_operator_tag ... ok
[INFO] [stdout] test trace_labels_level ... ok
[INFO] [stdout] test trace_preserves_execution ... ok
[INFO] [stdout] test trace_source_line_format ... ok
[INFO] [stdout] test trace_with_interpret ... ok
[INFO] [stdout] test trace_with_subroutine ... ok
[INFO] [stdout] test trace_result_format ... ok
[INFO] [stdout] test trace_single_letter ... ok
[INFO] [stdout] test trace_results_shows_source_line ... ok
[INFO] [stdout] test trace_results_shows_result ... ok
[INFO] [stdout] test trace_variable_tag ... ok
[INFO] [stdout] test trace_question_toggle ... ok
[INFO] [stdout] test trace_unary_tag ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/rexxfile.rs (/opt/rustwide/target/debug/deps/rexxfile-0dc6c097c3ea6018)
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test exit_code_zero ... ok
[INFO] [stdout] test exit_fractional_errors ... ok
[INFO] [stdout] test shebang_line_ignored ... ok
[INFO] [stdout] test exit_code_nonzero ... ok
[INFO] [stdout] test exit_code_42 ... ok
[INFO] [stdout] test exit_negative_code ... ok
[INFO] [stdout] test shebang_with_comment_and_code ... ok
[INFO] [stdout] test exit_code_from_variable ... ok
[INFO] [stdout] test exit_no_value_is_zero ... ok
[INFO] [stdout] test exit_non_numeric_errors ... ok
[INFO] [stdout] test no_shebang_still_works ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests patch_rexx
[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" "f6b91130505f24e2fa73c7473d8dddbb4770685732e88a4edacd48e26e26a5bf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f6b91130505f24e2fa73c7473d8dddbb4770685732e88a4edacd48e26e26a5bf", kill_on_drop: false }`
[INFO] [stdout] f6b91130505f24e2fa73c7473d8dddbb4770685732e88a4edacd48e26e26a5bf