[INFO] cloning repository https://github.com/k-ymmt/kish
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/k-ymmt/kish" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fk-ymmt%2Fkish", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fk-ymmt%2Fkish'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] ac1bd9e288c54f467d1b26c8cfbd599b8e02c32a
[INFO] testing k-ymmt/kish against 1.94.0 for beta-1.95-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fk-ymmt%2Fkish" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/k-ymmt/kish
[INFO] finished tweaking git repo https://github.com/k-ymmt/kish
[INFO] tweaked toml for git repo https://github.com/k-ymmt/kish written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/k-ymmt/kish on toolchain 1.94.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.94.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/k-ymmt/kish 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.94.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded clap v4.5.57
[INFO] [stderr]   Downloaded clap_builder v4.5.57
[INFO] [stderr]   Downloaded proptest v1.10.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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" "+1.94.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a5d6680940ef8da798b7d91a0f1559b23cf0a03b6ce8fb91650636d64b8be609
[INFO] running `Command { std: "docker" "start" "-a" "a5d6680940ef8da798b7d91a0f1559b23cf0a03b6ce8fb91650636d64b8be609", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a5d6680940ef8da798b7d91a0f1559b23cf0a03b6ce8fb91650636d64b8be609", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a5d6680940ef8da798b7d91a0f1559b23cf0a03b6ce8fb91650636d64b8be609", kill_on_drop: false }`
[INFO] [stdout] a5d6680940ef8da798b7d91a0f1559b23cf0a03b6ce8fb91650636d64b8be609
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.94.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f013fe30336ce7f033721df864682592d521d893bb1e9a0c5cf3f591391533b8
[INFO] running `Command { std: "docker" "start" "-a" "f013fe30336ce7f033721df864682592d521d893bb1e9a0c5cf3f591391533b8", kill_on_drop: false }`
[INFO] [stderr]    Compiling kish v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 3.56s
[INFO] running `Command { std: "docker" "inspect" "f013fe30336ce7f033721df864682592d521d893bb1e9a0c5cf3f591391533b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f013fe30336ce7f033721df864682592d521d893bb1e9a0c5cf3f591391533b8", kill_on_drop: false }`
[INFO] [stdout] f013fe30336ce7f033721df864682592d521d893bb1e9a0c5cf3f591391533b8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.94.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1e692f2236371d4e7103d9164d4f83bec61ce9ad64ba89ac34a97103f9f0cb7c
[INFO] running `Command { std: "docker" "start" "-a" "1e692f2236371d4e7103d9164d4f83bec61ce9ad64ba89ac34a97103f9f0cb7c", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling zerocopy v0.8.39
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zmij v1.0.19
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling clap_lex v0.7.7
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling clap_builder v4.5.57
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling clap v4.5.57
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling zerocopy-derive v0.8.39
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.10.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling kish v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 40.56s
[INFO] running `Command { std: "docker" "inspect" "1e692f2236371d4e7103d9164d4f83bec61ce9ad64ba89ac34a97103f9f0cb7c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1e692f2236371d4e7103d9164d4f83bec61ce9ad64ba89ac34a97103f9f0cb7c", kill_on_drop: false }`
[INFO] [stdout] 1e692f2236371d4e7103d9164d4f83bec61ce9ad64ba89ac34a97103f9f0cb7c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.94.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e88334b53364d4a782a7cf38f33af284925622e87f6fefed8650b2b5d9f63a50
[INFO] running `Command { std: "docker" "start" "-a" "e88334b53364d4a782a7cf38f33af284925622e87f6fefed8650b2b5d9f63a50", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kish-298f6c79b760bb61)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test ir::lower::emit::tests::arg_only_emits_standard_hint ... ok
[INFO] [stdout] test ir::lower::emit::tests::assignment_name_is_interned_as_symbol ... ok
[INFO] [stdout] test ir::lower::emit::tests::assignment_only_emits_no_command_hint ... ok
[INFO] [stdout] test ir::lower::emit::tests::assignment_plus_redirect_emits_no_command_hint ... ok
[INFO] [stdout] test ir::lower::emit::tests::multiple_assignments_only ... ok
[INFO] [stdout] test ir::lower::emit::tests::mixed_command_emits_standard_hint ... ok
[INFO] [stdout] test ir::lower::emit::tests::all_posix_special_builtins_detected ... ok
[INFO] [stdout] test ir::lower::emit::tests::assignment_value_creates_word_program ... ok
[INFO] [stdout] test ir::lower::emit::tests::redirect_only_emits_no_command_hint ... ok
[INFO] [stdout] test ir::lower::emit::tests::redirect_programs_created_for_redirects ... ok
[INFO] [stdout] test ir::lower::emit::tests::export_emits_special_builtin_hint ... ok
[INFO] [stdout] test ir::lower::emit::tests::word_programs_created_for_args ... ok
[INFO] [stdout] test ir::lower::emit::tests::non_special_builtin_emits_standard_hint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/kish-b9bffa7ec29313f8)
[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/mod.rs (/opt/rustwide/target/debug/deps/mod-54da179730d008f9)
[INFO] [stdout] 
[INFO] [stdout] running 743 tests
[INFO] [stdout] test ir::arith_program_lowering::bitwise_and ... ok
[INFO] [stdout] test ir::arith_program_lowering::bitwise_or ... ok
[INFO] [stdout] test ir::arith_program_lowering::bitwise_not ... ok
[INFO] [stdout] test ir::arith_program_lowering::bitwise_xor ... ok
[INFO] [stdout] test ir::arith_program_lowering::comma_expression ... ok
[INFO] [stdout] test ir::arith_program_lowering::comparison_equal ... ok
[INFO] [stdout] test ir::arith_program_lowering::comparison_less_equal ... ok
[INFO] [stdout] test ir::arith_program_lowering::comparison_greater_equal ... ok
[INFO] [stdout] test ir::arith_program_lowering::comparison_less_than ... ok
[INFO] [stdout] test ir::arith_program_lowering::comparison_not_equal ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_modulo ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_bitwise_and ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_divide ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_bitwise_or ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_bitwise_xor ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_plus ... ok
[INFO] [stdout] test ir::arith_program_lowering::decimal_literal ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_shift_left ... ok
[INFO] [stdout] test ir::arith_program_lowering::addition_and_multiplication_precedence ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_shift_right ... ok
[INFO] [stdout] test ir::arith_program_lowering::comparison_greater_than ... ok
[INFO] [stdout] test ir::arith_program_lowering::parenthesized_grouping ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_multiply ... ok
[INFO] [stdout] test ir::arith_program_lowering::extra_rparen_produces_error ... ok
[INFO] [stdout] test ir::arith_program_lowering::logical_and_has_short_circuit ... ok
[INFO] [stdout] test ir::arith_program_lowering::post_decrement ... ok
[INFO] [stdout] test ir::arith_program_lowering::post_increment ... ok
[INFO] [stdout] test ir::arith_program_lowering::logical_not ... ok
[INFO] [stdout] test ir::arith_program_lowering::compound_assignment_subtract ... ok
[INFO] [stdout] test ir::arith_program_lowering::hex_literal ... ok
[INFO] [stdout] test ir::arith_program_lowering::malformed_expression_produces_error ... ok
[INFO] [stdout] test ir::arith_program_lowering::modulo_operator ... ok
[INFO] [stdout] test ir::arith_program_lowering::pre_decrement ... ok
[INFO] [stdout] test ir::arith_program_lowering::pre_increment ... ok
[INFO] [stdout] test ir::arith_program_lowering::shift_left ... ok
[INFO] [stdout] test ir::arith_program_lowering::logical_or_has_short_circuit ... ok
[INFO] [stdout] test ir::arith_program_lowering::simple_assignment ... ok
[INFO] [stdout] test ir::arith_program_lowering::ternary_expression ... ok
[INFO] [stdout] test ir::arith_program_lowering::whitespace_in_expression ... ok
[INFO] [stdout] test ir::arith_program_lowering::unary_plus ... ok
[INFO] [stdout] test ir::arith_program_lowering::shift_right ... ok
[INFO] [stdout] test ir::arith_program_lowering::trailing_operator_produces_error ... ok
[INFO] [stdout] test ir::arith_program_lowering::unary_minus ... ok
[INFO] [stdout] test ir::arith_program_lowering::whitespace_only_expression_evaluates_to_zero ... ok
[INFO] [stdout] test ir::arith_program_lowering::word_program_has_expand_arithmetic_with_non_empty_arith_program ... ok
[INFO] [stdout] test ir::code_object_builder_positive::mixed_control_flow_patches_all_branches ... ok
[INFO] [stdout] test ir::code_object_builder_negative::instruction_limit_exceeded_returns_limit_exceeded ... ok
[INFO] [stdout] test ir::arith_program_lowering::zero_literal ... ok
[INFO] [stdout] test ir::code_object_builder_negative::unknown_label_usage_returns_invariant_violation ... ok
[INFO] [stdout] test ir::contracts::lower_program_succeeds ... ok
[INFO] [stdout] test ir::code_object_builder_negative::unresolved_label_at_finalize_returns_invariant_violation ... ok
[INFO] [stdout] test ir::code_object_builder_negative::duplicate_label_bind_returns_invariant_violation ... ok
[INFO] [stdout] test ir::branch_patch_determinism::branch_fixup_is_deterministic ... ok
[INFO] [stdout] test ir::contracts::lowering_context_retains_options_contract ... ok
[INFO] [stdout] test ir::contracts::vm_ir_builder_api_is_reachable ... ok
[INFO] [stdout] test ir::emit_control_flow::background_creates_sub_code_object ... ok
[INFO] [stdout] test ir::emit_control_flow::background_sub_code_object_has_ret ... ok
[INFO] [stdout] test ir::emit_control_flow::and_if_chain_emits_dup_jmp_if_nonzero ... ok
[INFO] [stdout] test ir::emit_control_flow::brace_group_emits_inline ... ok
[INFO] [stdout] test ir::emit_control_flow::and_or_mixed_chain ... ok
[INFO] [stdout] test ir::emit_control_flow::case_break_jumps_to_end ... ok
[INFO] [stdout] test ir::emit_control_flow::case_basic_structure ... ok
[INFO] [stdout] test ir::emit_control_flow::and_or_single_pipeline_no_extra_instructions ... ok
[INFO] [stdout] test ir::emit_control_flow::case_fallthrough_jumps_to_next_body ... ok
[INFO] [stdout] test ir::emit_control_flow::case_multiple_patterns ... ok
[INFO] [stdout] test ir::emit_control_flow::compound_in_pipeline ... ok
[INFO] [stdout] test ir::emit_control_flow::for_loop_basic_structure ... ok
[INFO] [stdout] test ir::emit_control_flow::for_loop_without_words ... ok
[INFO] [stdout] test ir::code_object_builder_positive::backward_jump_is_patched_to_absolute_target ... ok
[INFO] [stdout] test ir::emit_control_flow::if_else_has_no_default_push ... ok
[INFO] [stdout] test ir::emit_control_flow::for_loop_interns_variable_symbol ... ok
[INFO] [stdout] test ir::emit_control_flow::list_single_command_no_drop ... ok
[INFO] [stdout] test ir::emit_control_flow::function_definition_creates_body_code_object ... ok
[INFO] [stdout] test ir::code_object_builder_positive::forward_jump_is_patched_to_absolute_target ... ok
[INFO] [stdout] test ir::emit_control_flow::if_elif_else_structure ... ok
[INFO] [stdout] test ir::emit_control_flow::if_no_else_pushes_default_zero ... ok
[INFO] [stdout] test ir::emit_control_flow::function_definition_emits_define_function ... ok
[INFO] [stdout] test ir::emit_control_flow::if_then_fi_basic_structure ... ok
[INFO] [stdout] test ir::emit_control_flow::multi_command_pipeline_emits_sub_code_objects ... ok
[INFO] [stdout] test ir::emit_control_flow::negated_pipeline_emits_negate_status ... ok
[INFO] [stdout] test ir::emit_control_flow::simple_command_single_word ... ok
[INFO] [stdout] test ir::emit_control_flow::or_if_chain_emits_dup_jmp_if_zero ... ok
[INFO] [stdout] test ir::emit_control_flow::nested_if_in_while ... ok
[INFO] [stdout] test ir::emit_control_flow::single_command_pipeline_emits_inline ... ok
[INFO] [stdout] test ir::emit_control_flow::program_multiple_commands_drops_between ... ok
[INFO] [stdout] test ir::emit_control_flow::subshell_sub_code_object_has_ret ... ok
[INFO] [stdout] test ir::emit_control_flow::until_loop_uses_jmp_if_zero ... ok
[INFO] [stdout] test ir::encode::empty_module_encodes_to_empty ... ok
[INFO] [stdout] test ir::emit_control_flow::while_loop_backward_jump ... ok
[INFO] [stdout] test ir::encode::encode_add_arg_small ... ok
[INFO] [stdout] test ir::emit_control_flow::while_loop_structure ... ok
[INFO] [stdout] test ir::encode::encode_add_assign ... ok
[INFO] [stdout] test ir::encode::encode_add_assign_wide_first_operand ... ok
[INFO] [stdout] test ir::encode::decode_tracks_offsets_correctly ... ok
[INFO] [stdout] test ir::encode::encode_add_redir_small ... ok
[INFO] [stdout] test ir::encode::encode_add_pipeline_stage_small ... ok
[INFO] [stdout] test ir::encode::encode_arith_compound_assign_all_ops ... ok
[INFO] [stdout] test ir::encode::encode_arith_compound_assign ... ok
[INFO] [stdout] test ir::encode::encode_arith_inc_dec ... ok
[INFO] [stdout] test ir::encode::encode_arith_jumps ... ok
[INFO] [stdout] test ir::encode::encode_arith_push_literal ... ok
[INFO] [stdout] test ir::encode::encode_arith_push_literal_negative ... ok
[INFO] [stdout] test ir::encode::encode_arith_load_variable ... ok
[INFO] [stdout] test ir::encode::encode_arith_wide_operand ... ok
[INFO] [stdout] test ir::encode::encode_arith_zero_operand_ops ... ok
[INFO] [stdout] test ir::encode::encode_begin_pipeline_small ... ok
[INFO] [stdout] test ir::encode::encode_branch_small ... ok
[INFO] [stdout] test ir::encode::encode_case_set_subject_small ... ok
[INFO] [stdout] test ir::encode::encode_call_small ... ok
[INFO] [stdout] test ir::encode::encode_define_function ... ok
[INFO] [stdout] test ir::encode::encode_case_test_pattern_small ... ok
[INFO] [stdout] test ir::encode::encode_exec_background_small ... ok
[INFO] [stdout] test ir::contracts::encode_and_verify_public_contracts_exist ... ok
[INFO] [stdout] test ir::encode::encode_exec_subshell_small ... ok
[INFO] [stdout] test ir::emit_control_flow::negated_multi_pipeline_emits_negate_after_exec ... ok
[INFO] [stdout] test ir::emit_control_flow::subshell_creates_sub_code_object ... ok
[INFO] [stdout] test ir::contracts::lower_complete_command_succeeds ... ok
[INFO] [stdout] test ir::encode::encode_exec_simple_standard ... ok
[INFO] [stdout] test ir::emit_control_flow::simple_command_single_produces_one_code_object ... ok
[INFO] [stdout] test ir::encode::encode_for_add_word_small ... ok
[INFO] [stdout] test ir::encode::encode_exec_simple_no_command ... ok
[INFO] [stdout] test ir::encode::encode_for_setup ... ok
[INFO] [stdout] test ir::encode::encode_push_int_i64_max ... ok
[INFO] [stdout] test ir::emit_control_flow::list_three_commands_has_two_drops ... ok
[INFO] [stdout] test ir::encode::encode_local_get_set ... ok
[INFO] [stdout] test ir::encode::encode_arith_assign ... ok
[INFO] [stdout] test ir::encode::encode_exec_simple_special_builtin ... ok
[INFO] [stdout] test ir::encode::encode_push_int_i64_min ... ok
[INFO] [stdout] test ir::emit_control_flow::simple_command_emits_begin_end_exec ... ok
[INFO] [stdout] test ir::encode::encode_push_int_negative ... ok
[INFO] [stdout] test ir::encode::encode_push_int_zero ... ok
[INFO] [stdout] test ir::encode::encode_push_int_positive ... ok
[INFO] [stdout] test ir::encode::encode_push_string_small ... ok
[INFO] [stdout] test ir::emit_control_flow::list_two_commands_has_drop_between ... ok
[INFO] [stdout] test ir::arith_program_lowering::variable_reference ... ok
[INFO] [stdout] test ir::encode::encode_push_symbol_small ... ok
[INFO] [stdout] test ir::encode::encode_redirect_heredoc_expand ... ok
[INFO] [stdout] test ir::encode::encode_redirect_heredoc_no_expand ... ok
[INFO] [stdout] test ir::encode::encode_redirect_open_read_write ... ok
[INFO] [stdout] test ir::encode::encode_redirect_open ... ok
[INFO] [stdout] test ir::encode::encode_single_operand_small ... ok
[INFO] [stdout] test ir::encode::encode_redirect_open_append ... ok
[INFO] [stdout] test ir::encode::encode_redirect_open_clobber ... ok
[INFO] [stdout] test ir::encode::encode_wide_operand_large_value ... ok
[INFO] [stdout] test ir::encode::encode_word_expand_arithmetic ... ok
[INFO] [stdout] test ir::encode::encode_word_expand_command_substitution ... ok
[INFO] [stdout] test ir::encode::encode_word_expand_parameter ... ok
[INFO] [stdout] test ir::encode::encode_word_expand_tilde ... ok
[INFO] [stdout] test ir::encode::encode_word_wide_operand ... ok
[INFO] [stdout] test ir::encode::encode_word_push_literal ... ok
[INFO] [stdout] test ir::encode::encode_wide_operand ... ok
[INFO] [stdout] test ir::encode::encode_wide_branch_target ... ok
[INFO] [stdout] test ir::encode::encode_word_zero_operand_ops ... ok
[INFO] [stdout] test ir::encode::encode_zero_operand_instructions ... ok
[INFO] [stdout] test ir::encode::encoded_code_object_source_map_is_empty ... ok
[INFO] [stdout] test ir::encode::full_pipeline_program ... ok
[INFO] [stdout] test ir::encode::encoding_is_deterministic ... ok
[INFO] [stdout] test ir::encode::round_trip_arith_compound_assign ... ok
[INFO] [stdout] test ir::encode::full_pipeline_echo_hello ... ok
[INFO] [stdout] test ir::encode::encode_redirect_close ... ok
[INFO] [stdout] test ir::encode::encode_redirect_dup ... ok
[INFO] [stdout] test ir::encode::encode_redirect_open_readonly ... ok
[INFO] [stdout] test ir::encode::pools_are_preserved ... ok
[INFO] [stdout] test ir::arith_program_lowering::octal_literal ... ok
[INFO] [stdout] test ir::encode::round_trip_arith_program ... ok
[INFO] [stdout] test ir::encode::round_trip_redirect_dup ... ok
[INFO] [stdout] test ir::arith_program_lowering::subtraction_and_division ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_define_function ... ok
[INFO] [stdout] test ir::encode::round_trip_redirect_program ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_exec_simple_hints ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_push_int ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_for_setup ... ok
[INFO] [stdout] test ir::encode::round_trip_redirect_heredoc ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_single_operand ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_two_operand ... ok
[INFO] [stdout] test ir::encode::source_map_entry_default ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_arithmetic ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_and_or ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_wide_operand ... ok
[INFO] [stdout] test ir::hir_builder_negative::assignment_name_validation_rejects_invalid_shapes ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_complex ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_subshell ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_if_else ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_pipeline ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_for_loop ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_function ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_assignment ... ok
[INFO] [stdout] test ir::encode::round_trip_vm_zero_operand ... ok
[INFO] [stdout] test ir::encode::round_trip_word_program ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_while_loop ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_background ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_case ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_redirect ... ok
[INFO] [stdout] test ir::hir_builder_negative::function_definition_requires_compound_body ... ok
[INFO] [stdout] test ir::golden_snapshots::snapshot_echo ... ok
[INFO] [stdout] test ir::hir_builder_negative::list_requires_at_least_one_and_or ... ok
[INFO] [stdout] test ir::hir_builder_negative::redirect_requires_numeric_fd_token ... ok
[INFO] [stdout] test ir::hir_builder_negative::pipeline_requires_at_least_one_command ... ok
[INFO] [stdout] test ir::hir_builder_negative::structural_inconsistency_returns_invariant_violation ... ok
[INFO] [stdout] test ir::hir_builder_negative::redirect_rejects_unsupported_operator ... ok
[INFO] [stdout] test ir::hir_builder_negative::case_item_requires_patterns ... ok
[INFO] [stdout] test ir::hir_builder_positive::builder_constructs_all_compound_variants_and_function_definition ... ok
[INFO] [stdout] test ir::hir_builder_positive::builder_constructs_simple_command_with_assignment_word_and_redirect ... ok
[INFO] [stdout] test ir::hir_builder_positive::conversion_helpers_map_semantics ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_subshell ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_if_simple ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_case ... ok
[INFO] [stdout] test ir::hir_mapping_exhaustive::operator_mappings_are_exhaustive_for_hir_semantics ... ok
[INFO] [stdout] test ir::hir_builder_positive::builder_constructs_complete_command_and_program ... ok
[INFO] [stdout] test ir::hir_contracts::hir_contract_types_are_public_and_constructible ... ok
[INFO] [stdout] test ir::hir_mapping_exhaustive::parser_case_terminators_map_to_hir_case_terminators ... ok
[INFO] [stdout] test ir::hir_mapping_exhaustive::parser_command_variants_map_to_hir_targets_exhaustively ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_until ... ok
[INFO] [stdout] test ir::hir_mapping_exhaustive::parser_compound_variants_map_to_hir_targets_exhaustively ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_if_full ... ok
[INFO] [stdout] test ir::hir_mapping_exhaustive::parser_program_contract_still_exists_for_phase4_lowering ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_while ... ok
[INFO] [stdout] test ir::lower_from_parser::and_or_chain ... ok
[INFO] [stdout] test ir::lower_from_parser::complete_command_with_background ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_brace_group ... ok
[INFO] [stdout] test ir::lower_from_parser::empty_program ... ok
[INFO] [stdout] test ir::lower_from_parser::compound_for ... ok
[INFO] [stdout] test ir::lower_from_parser::function_definition ... ok
[INFO] [stdout] test ir::lower_from_parser::list_with_semicolons ... ok
[INFO] [stdout] test ir::lower_from_parser::origin_tracking_compound_if ... ok
[INFO] [stdout] test ir::lower_from_parser::pipeline_two_commands ... ok
[INFO] [stdout] test ir::lower_from_parser::multiple_complete_commands ... ok
[INFO] [stdout] test ir::lower_from_parser::simple_command_echo_hello ... ok
[INFO] [stdout] test ir::negative_lowering::and_or_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::arith_exceeds_op_limit ... ok
[INFO] [stdout] test ir::lower_from_parser::pipeline_negated ... ok
[INFO] [stdout] test ir::lower_from_parser::origin_tracking_function_definition ... ok
[INFO] [stdout] test ir::negative_lowering::background_exceeds_code_object_limit ... ok
[INFO] [stdout] test ir::negative_lowering::brace_group_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::case_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::lower_from_parser::simple_command_with_assignment ... ok
[INFO] [stdout] test ir::hir_builder_negative::simple_command_requires_any_content ... ok
[INFO] [stdout] test ir::lower_from_parser::origin_tracking_simple_command ... ok
[INFO] [stdout] test ir::lower_from_parser::origin_tracking_assignment_and_redirect ... ok
[INFO] [stdout] test ir::lower_from_parser::simple_command_with_output_redirect ... ok
[INFO] [stdout] test ir::negative_lowering::assignment_exceeds_word_program_limit ... ok
[INFO] [stdout] test ir::lower_from_parser::simple_command_with_fd_redirect ... ok
[INFO] [stdout] test ir::negative_lowering::pipeline_exceeds_code_object_limit ... ok
[INFO] [stdout] test ir::negative_lowering::for_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::function_exceeds_code_object_limit ... ok
[INFO] [stdout] test ir::negative_lowering::list_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::simple_command_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::if_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::until_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::while_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::complete_command_exceeds_word_program_ops ... ok
[INFO] [stdout] test ir::negative_lowering::subshell_exceeds_code_object_limit ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_and_or_chain ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_background_command ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_brace_group ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_case_command ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_all_subprogram_types ... ok
[INFO] [stdout] test ir::negative_lowering::program_exceeds_code_object_limit ... ok
[INFO] [stdout] test ir::negative_lowering::redirect_exceeds_op_limit ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_simple_command ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_pipeline ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_case_fallthrough ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_simple_command_with_assignments ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_debug_verifier_no_warnings ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_if_then ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_list_with_semicolons ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_until_loop ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_multi_command_program ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_nested_compound ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_negated_pipeline ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_if_then_else ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_while_loop ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_for_loop ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_function_definition ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_if_elif_else ... ok
[INFO] [stdout] test ir::pipeline_end_to_end::e2e_subshell ... ok
[INFO] [stdout] test ir::pooling_interning::constant_interning_deduplicates_integer_string_and_symbol_entries ... ok
[INFO] [stdout] test ir::pooling_interning::max_consts_limit_is_enforced_only_for_unique_constants ... ok
[INFO] [stdout] test ir::pooling_interning::lowering_context_can_create_module_builder_with_same_options ... ok
[INFO] [stdout] test ir::pooling_interning::module_instruction_limit_is_enforced_cumulatively ... ok
[INFO] [stdout] test ir::pooling_interning::invalid_constant_reference_handles_are_rejected ... ok
[INFO] [stdout] test ir::redirect_program_lowering::append_redirect ... ok
[INFO] [stdout] test ir::redirect_program_lowering::compound_without_redirect_has_no_scope ... ok
[INFO] [stdout] test ir::pooling_interning::string_and_symbol_interning_are_deduplicated_with_stable_ids ... ok
[INFO] [stdout] test ir::redirect_program_lowering::clobber_redirect ... ok
[INFO] [stdout] test ir::redirect_program_lowering::dup_output_default_fd ... ok
[INFO] [stdout] test ir::redirect_program_lowering::explicit_fd ... ok
[INFO] [stdout] test ir::redirect_program_lowering::heredoc_quoted ... ok
[INFO] [stdout] test ir::redirect_program_lowering::heredoc_unquoted ... ok
[INFO] [stdout] test ir::redirect_program_lowering::input_redirect ... ok
[INFO] [stdout] test ir::redirect_program_lowering::multiple_redirects_order ... ok
[INFO] [stdout] test ir::redirect_program_lowering::dup_input_default_fd ... ok
[INFO] [stdout] test ir::redirect_program_lowering::output_redirect ... ok
[INFO] [stdout] test ir::redirect_program_lowering::dup_input ... ok
[INFO] [stdout] test ir::redirect_program_lowering::readwrite_redirect ... ok
[INFO] [stdout] test ir::redirect_program_lowering::redirect_only_has_ops ... ok
[INFO] [stdout] test ir::pooling_interning::code_object_count_limit_is_enforced_and_ids_are_reassigned ... ok
[INFO] [stdout] test ir::redirect_program_lowering::close_fd ... ok
[INFO] [stdout] test ir::redirect_program_lowering::compound_with_redirect ... ok
[INFO] [stdout] test ir::scaling::encode_pass_scales_linearly ... ok
[INFO] [stdout] test ir::redirect_program_lowering::dup_output ... ok
[INFO] [stdout] test ir::pooling_interning::word_and_redirect_program_op_limits_are_enforced_per_program ... ok
[INFO] [stdout] test ir::stress::arith_program_count_limit_enforced ... ok
[INFO] [stdout] test ir::stress::deduplicated_strings_do_not_count_against_limit ... ok
[INFO] [stdout] test ir::redirect_program_lowering::word_program_for_target ... ok
[INFO] [stdout] test ir::stress::deduplicated_symbols_do_not_count_against_limit ... ok
[INFO] [stdout] test ir::stress::redirect_program_count_limit_enforced ... ok
[INFO] [stdout] test ir::stress::string_pool_limit_enforced ... ok
[INFO] [stdout] test ir::stress::symbol_pool_limit_enforced ... ok
[INFO] [stdout] test ir::stress::word_program_count_limit_enforced ... ok
[INFO] [stdout] test ir::verify::arith_program_id_mismatch ... ok
[INFO] [stdout] test ir::stress::nesting_depth_limit_enforced ... ok
[INFO] [stdout] test ir::verify::arith_program_jmp_if_non_zero_target_overflow ... ok
[INFO] [stdout] test ir::verify::arith_program_jmp_if_zero_target_overflow ... ok
[INFO] [stdout] test ir::verify::arith_program_jump_target_overflow ... ok
[INFO] [stdout] test ir::verify::arith_program_out_of_bounds_compound_assign ... ok
[INFO] [stdout] test ir::verify::arith_program_out_of_bounds_post_decrement ... ok
[INFO] [stdout] test ir::verify::arith_program_out_of_bounds_symbol_assign ... ok
[INFO] [stdout] test ir::stress::many_unique_strings_via_lowering ... ok
[INFO] [stdout] test ir::verify::arith_program_out_of_bounds_symbol_load ... ok
[INFO] [stdout] test ir::verify::code_object_add_assign_invalid_symbol ... ok
[INFO] [stdout] test ir::verify::code_object_branch_target_one_past_end_is_valid ... ok
[INFO] [stdout] test ir::verify::code_object_branch_target_overflow ... ok
[INFO] [stdout] test ir::verify::code_object_define_function_invalid_refs ... ok
[INFO] [stdout] test ir::verify::code_object_exec_subshell_invalid ... ok
[INFO] [stdout] test ir::verify::code_object_id_mismatch ... ok
[INFO] [stdout] test ir::verify::code_object_local_id_overflow ... ok
[INFO] [stdout] test ir::verify::code_object_out_of_bounds_add_arg ... ok
[INFO] [stdout] test ir::verify::arith_program_out_of_bounds_pre_increment ... ok
[INFO] [stdout] test ir::verify::code_object_out_of_bounds_add_redir ... ok
[INFO] [stdout] test ir::verify::code_object_out_of_bounds_call ... ok
[INFO] [stdout] test ir::verify::code_object_out_of_bounds_push_const ... ok
[INFO] [stdout] test ir::verify::code_object_out_of_bounds_push_string ... ok
[INFO] [stdout] test ir::verify::code_object_out_of_bounds_push_symbol ... ok
[INFO] [stdout] test ir::verify::const_pool_invalid_symbol_ref ... ok
[INFO] [stdout] test ir::verify::debug_verifier_detects_dead_code_object ... ok
[INFO] [stdout] test ir::verify::debug_verifier_detects_unreachable_code ... ok
[INFO] [stdout] test ir::verify::debug_verifier_empty_module_no_warnings ... ok
[INFO] [stdout] test ir::verify::full_pipeline_complex_program ... ok
[INFO] [stdout] test ir::verify::debug_verifier_no_warnings_for_valid_lowered_module ... ok
[INFO] [stdout] test ir::verify::empty_module_verifies ... ok
[INFO] [stdout] test ir::verify::full_pipeline_parse_lower_verify_encode ... ok
[INFO] [stdout] test ir::verify::orphan_add_pipeline_stage ... ok
[INFO] [stdout] test ir::verify::orphan_for_add_word ... ok
[INFO] [stdout] test ir::verify::orphan_exec_pipeline ... ok
[INFO] [stdout] test ir::verify::redirect_program_id_mismatch ... ok
[INFO] [stdout] test ir::verify::redirect_program_out_of_bounds_string ... ok
[INFO] [stdout] test ir::verify::redirect_program_out_of_bounds_word_program ... ok
[INFO] [stdout] test ir::verify::unbalanced_begin_simple_unclosed ... ok
[INFO] [stdout] test ir::verify::unbalanced_end_simple_without_begin ... ok
[INFO] [stdout] test ir::verify::unbalanced_pop_redirect_scope ... ok
[INFO] [stdout] test ir::verify::unbalanced_push_redirect_scope_unclosed ... ok
[INFO] [stdout] test ir::verify::valid_case_module_verifies ... ok
[INFO] [stdout] test ir::verify::valid_control_flow_module_verifies ... ok
[INFO] [stdout] test ir::verify::incomplete_for_setup ... ok
[INFO] [stdout] test ir::verify::valid_for_loop_module_verifies ... ok
[INFO] [stdout] test ir::verify::valid_module_with_all_pool_types_verifies ... ok
[INFO] [stdout] test ir::verify::valid_pipeline_module_verifies ... ok
[INFO] [stdout] test ir::verify::word_program_out_of_bounds_arith ... ok
[INFO] [stdout] test ir::verify::word_program_out_of_bounds_code_object ... ok
[INFO] [stdout] test ir::verify::word_program_out_of_bounds_string ... ok
[INFO] [stdout] test ir::verify::const_pool_invalid_string_ref ... ok
[INFO] [stdout] test ir::verify::word_program_out_of_bounds_symbol ... ok
[INFO] [stdout] test ir::vm_ir_contracts::branch_target_accessors_are_stable ... ok
[INFO] [stdout] test ir::verify::pipeline_stage_count_mismatch ... ok
[INFO] [stdout] test ir::vm_ir_contracts::instruction_variants_cover_phase2_contract ... ok
[INFO] [stdout] test ir::verify::valid_simple_command_module_verifies ... ok
[INFO] [stdout] test ir::word_program_lowering::assignment_value_plain ... ok
[INFO] [stdout] test ir::verify::word_program_id_mismatch ... ok
[INFO] [stdout] test ir::word_program_lowering::assignment_value_has_no_field_split_or_glob ... ok
[INFO] [stdout] test ir::word_program_lowering::assignment_value_word_program_count ... ok
[INFO] [stdout] test ir::word_program_lowering::bare_parameter_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::braced_parameter_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::case_subject_has_word_program ... ok
[INFO] [stdout] test ir::word_program_lowering::command_argument_has_field_split_and_glob ... ok
[INFO] [stdout] test ir::word_program_lowering::for_loop_word_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::mixed_literal_and_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::tilde_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::word_program_counts_preserved ... ok
[INFO] [stdout] test ir::word_program_lowering::word_programs_not_empty_for_simple_command ... ok
[INFO] [stdout] test lexer::alias_substitution::does_not_expand_alias_outside_command_position ... ok
[INFO] [stdout] test ir::word_program_lowering::assignment_with_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::plain_literal_word_contains_push_literal ... ok
[INFO] [stdout] test lexer::alias_substitution::expands_after_command_separator ... ok
[INFO] [stdout] test lexer::alias_substitution::recursion_guard_prevents_infinite_self_alias_expansion ... ok
[INFO] [stdout] test lexer::alias_substitution::expands_alias_in_command_position ... ok
[INFO] [stdout] test lexer::alias_substitution::quoted_or_invalid_alias_names_are_not_expanded ... ok
[INFO] [stdout] test lexer::boundary_api::boundary_api_can_report_need_more_input ... ok
[INFO] [stdout] test lexer::boundary_api::boundary_api_returns_complete_on_basic_input ... ok
[INFO] [stdout] test lexer::boundary_api::interactive_mode_keeps_unterminated_quote_as_need_more_input ... ok
[INFO] [stdout] test lexer::alias_substitution::trailing_blank_continuation_can_be_enabled_or_disabled ... ok
[INFO] [stdout] test lexer::boundary_api::non_interactive_mode_returns_fatal_incomplete_for_trailing_backslash ... ok
[INFO] [stdout] test lexer::boundary_api::non_interactive_mode_returns_fatal_for_unterminated_quote ... ok
[INFO] [stdout] test lexer::alias_substitution::retokenizes_alias_value_and_preserves_source_mapping ... ok
[INFO] [stdout] test ir::word_program_lowering::single_quoted_no_expansion ... ok
[INFO] [stdout] test ir::word_program_lowering::plain_literal_has_trailing_field_split_glob_quote_removal ... ok
[INFO] [stdout] test lexer::comment_handling::comment_after_word_discards_comment_text_and_keeps_newline ... ok
[INFO] [stdout] test lexer::comment_handling::hash_inside_word_is_not_comment_start ... ok
[INFO] [stdout] test lexer::comment_handling::leading_comment_line_preserves_newline_token ... ok
[INFO] [stdout] test lexer::comment_handling::quoted_hash_does_not_start_comment ... ok
[INFO] [stdout] test lexer::constructor_mode::lexer_new_sets_mode ... ok
[INFO] [stdout] test lexer::cursor_position::ascii_advance_updates_column_and_newline_resets_column ... ok
[INFO] [stdout] test lexer::cursor_position::initial_position_is_one_based ... ok
[INFO] [stdout] test lexer::cursor_position::utf8_continuation_byte_does_not_increment_column ... ok
[INFO] [stdout] test lexer::diagnostic_stability::heredoc_warning_diagnostic_fields_are_stable ... ok
[INFO] [stdout] test lexer::diagnostic_stability::unterminated_single_quote_diagnostic_fields_are_stable ... ok
[INFO] [stdout] test lexer::error_types::diagnostic_with_context_populates_optional_fields ... ok
[INFO] [stdout] test lexer::error_types::recoverable_and_fatal_errors_are_distinct_types ... ok
[INFO] [stdout] test lexer::golden_tokenization::mixed_quotes_case_matches_golden ... ok
[INFO] [stdout] test lexer::golden_tokenization::multiple_heredocs_case_matches_golden ... ok
[INFO] [stdout] test lexer::golden_tokenization::nested_substitution_case_matches_golden ... ok
[INFO] [stdout] test lexer::golden_tokenization::pipeline_list_case_matches_golden ... ok
[INFO] [stdout] test lexer::golden_tokenization::simple_command_case_matches_golden ... ok
[INFO] [stdout] test lexer::guardrails::boundary_token_count_exceed_limit_returns_boundary_limit_error ... ok
[INFO] [stdout] test lexer::guardrails::heredoc_body_exceed_limit_returns_heredoc_size_error ... ok
[INFO] [stdout] test lexer::guardrails::token_exceed_limit_returns_token_size_error ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::boundary_api_is_complete_in_warning_mode ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::boundary_api_reports_missing_here_doc_delimiter_as_need_more ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::captures_here_doc_body_payload_and_metadata ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::drain_accessors_clear_collected_values ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::multiple_here_docs_are_consumed_in_encounter_order ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::next_token_reports_missing_here_doc_delimiter_as_fatal ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::quoted_delimiter_uses_quote_removed_key ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::strip_tabs_mode_matches_delimiter_with_leading_tabs ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::strip_tabs_mode_strips_tabs_from_stored_body_lines ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::warning_policy_captures_remaining_pending_heredocs_as_empty ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::warning_policy_keeps_partial_body_and_records_warning ... ok
[INFO] [stdout] test lexer::line_continuation::line_continuation_between_words_does_not_emit_newline_token ... ok
[INFO] [stdout] test lexer::line_continuation::repeated_next_token_after_line_join_does_not_panic ... ok
[INFO] [stdout] test lexer::line_continuation::unquoted_line_continuation_joins_token_without_separator ... ok
[INFO] [stdout] test lexer::newline_preservation::emits_newline_tokens_for_regular_line_ends ... ok
[INFO] [stdout] test lexer::newline_preservation::removed_line_continuation_does_not_emit_intermediate_newline ... ok
[INFO] [stdout] test lexer::next_token_behavior::next_token_smoke_does_not_panic ... ok
[INFO] [stdout] test lexer::operator_scanning::honors_longest_match_precedence ... ok
[INFO] [stdout] test lexer::operator_scanning::scans_all_operators_with_exact_lexemes ... ok
[INFO] [stdout] test lexer::cursor_position::checkpoint_and_rollback_restore_all_position_fields ... ok
[INFO] [stdout] test lexer::diagnostic_stability::malformed_arithmetic_diagnostic_fields_are_stable ... ok
[INFO] [stdout] test lexer::heredoc_mode_machine::here_doc_line_tokens_are_replayed_and_body_is_consumed ... ok
[INFO] [stdout] test lexer::parser_handoff_contract::lexer_stream_remains_raw_token_operator_newline_only ... ok
[INFO] [stdout] test lexer::performance_benchmarks::bench_long_line_tokenization_ignored ... ignored
[INFO] [stdout] test lexer::performance_benchmarks::bench_nested_substitution_tokenization_ignored ... ignored
[INFO] [stdout] test lexer::line_continuation::trailing_unquoted_backslash_requests_more_input ... ok
[INFO] [stdout] test lexer::parser_handoff_contract::quoted_or_substituted_tokens_remain_token_class_under_delimiter_context ... ok
[INFO] [stdout] test lexer::parser_handoff_contract::parser_side_lookahead_can_classify_io_number_without_mutating_stream_kind ... ok
[INFO] [stdout] test lexer::quote_scanning::boundary_reports_unterminated_dollar_single_quote ... ok
[INFO] [stdout] test lexer::quote_scanning::boundary_reports_unterminated_double_quote ... ok
[INFO] [stdout] test lexer::quote_scanning::quote_markers_preserve_ranges_and_lexeme ... ok
[INFO] [stdout] test lexer::quote_scanning::unterminated_dollar_single_quote_returns_fatal_error ... ok
[INFO] [stdout] test lexer::quote_scanning::unterminated_single_quote_returns_fatal_error ... ok
[INFO] [stdout] test lexer::parser_handoff_contract::io_location_classification_is_gated_by_explicit_parser_option ... ok
[INFO] [stdout] test lexer::source_span_types::source_and_span_contracts_hold_ordering ... ok
[INFO] [stdout] test lexer::substitution_scanning::delimiters_inside_substitution_do_not_split_token ... ok
[INFO] [stdout] test lexer::substitution_scanning::depth_overflow_returns_fatal_error ... ok
[INFO] [stdout] test lexer::substitution_scanning::malformed_arithmetic_expansion_returns_fatal_error ... ok
[INFO] [stdout] test lexer::substitution_scanning::marker_creation_for_recursive_substitution_forms ... ok
[INFO] [stdout] test lexer::substitution_scanning::nesting_keeps_single_token_and_collects_overlapping_markers ... ok
[INFO] [stdout] test lexer::substitution_scanning::substitution_recognized_in_double_quotes_but_not_single_quotes ... ok
[INFO] [stdout] test lexer::substitution_scanning::boundary_reports_unterminated_substitution_need_more_reason ... ok
[INFO] [stdout] test lexer::substitution_scanning::unterminated_substitutions_return_fatal_error_from_next_token ... ok
[INFO] [stdout] test lexer::token_model::classify_for_parser_can_enable_io_location_gate ... ok
[INFO] [stdout] test lexer::token_model::classify_for_parser_gives_io_number_precedence ... ok
[INFO] [stdout] test lexer::token_model::classify_for_parser_keeps_io_location_disabled_by_default ... ok
[INFO] [stdout] test lexer::token_model::classify_for_parser_never_converts_non_plain_or_non_token_values ... ok
[INFO] [stdout] test lexer::token_model::classify_for_parser_recognizes_io_number_only_with_delimiter_context ... ok
[INFO] [stdout] test lexer::token_model::classify_for_parser_rejects_invalid_io_location_shape_even_when_enabled ... ok
[INFO] [stdout] test lexer::token_model::io_location_helper_uses_braced_identifier_shape ... ok
[INFO] [stdout] test lexer::token_model::io_number_helper_accepts_plain_digits_token_only ... ok
[INFO] [stdout] test lexer::token_model::operator_kind_delimiter_context_maps_redirection_operators ... ok
[INFO] [stdout] test lexer::token_model::operator_kind_covers_phase1_operator_set ... ok
[INFO] [stdout] test lexer::token_model::token_preserves_raw_text_span_and_markers ... ok
[INFO] [stdout] test lexer::word_boundaries::mixed_stream_order_is_preserved ... ok
[INFO] [stdout] test lexer::word_boundaries::io_number_like_text_stays_raw_token_in_lexer_stream ... ok
[INFO] [stdout] test lexer::word_boundaries::operators_delimit_words_without_merging ... ok
[INFO] [stdout] test lexer::word_boundaries::unquoted_blanks_delimit_words ... ok
[INFO] [stdout] test parser::arena_builders::arena_allocation_limit_failure_is_deterministic ... ok
[INFO] [stdout] test parser::arena_builders::ast_builder_creates_nodes_and_tracks_allocations ... ok
[INFO] [stdout] test parser::arena_builders::ast_builder_limit_overflow_maps_to_parse_error ... ok
[INFO] [stdout] test lexer::word_boundaries::quoted_operator_and_blank_stay_in_single_token ... ok
[INFO] [stdout] test parser::arena_builders::parser_non_empty_input_respects_max_ast_nodes_guard ... ok
[INFO] [stdout] test parser::classifier::assignment_word_rule_handles_valid_and_invalid_prefixes ... ok
[INFO] [stdout] test parser::classifier::for_name_context_maps_valid_name_only ... ok
[INFO] [stdout] test parser::classifier::io_number_and_io_location_classification_follow_context_and_options ... ok
[INFO] [stdout] test parser::classifier::quoted_tokens_are_not_reserved_words ... ok
[INFO] [stdout] test parser::arena_builders::parse_program_empty_input_uses_program_node_allocation ... ok
[INFO] [stdout] test parser::classifier::reserved_words_only_match_when_policy_allows ... ok
[INFO] [stdout] test parser::classifier::rule9_function_body_bypasses_assignment_conversion ... ok
[INFO] [stdout] test parser::contracts::parse_options_default_matches_phase_contract ... ok
[INFO] [stdout] test parser::contracts::parse_step_and_error_kinds_are_public_contracts ... ok
[INFO] [stdout] test parser::contracts::parser_constructor_and_source_id_are_available ... ok
[INFO] [stdout] test parser::contracts::parse_program_returns_empty_program_on_empty_input ... ok
[INFO] [stdout] test parser::grammar_skeleton::and_or_chain_parses ... ok
[INFO] [stdout] test lexer::property_cursor_invariants::checkpoint_and_rollback_match_fresh_cursor_position ... ok
[INFO] [stdout] test parser::grammar_skeleton::assignment_like_word_in_suffix_stays_word ... ok
[INFO] [stdout] test parser::grammar_skeleton::brace_group_compound_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::assignment_prefix_with_command_words_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::assignment_only_simple_command_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::compound_redirects_attach_and_preserve_order ... ok
[INFO] [stdout] test parser::grammar_skeleton::case_non_terminated_item_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::function_body_rule9_keeps_assignment_like_tokens_as_words ... ok
[INFO] [stdout] test parser::grammar_skeleton::for_forms_parse ... ok
[INFO] [stdout] test parser::grammar_skeleton::function_definition_parses_brace_group_body ... ok
[INFO] [stdout] test parser::grammar_skeleton::case_with_terminators_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::function_definition_redirects_attach_to_function_body_wrapper ... ok
[INFO] [stdout] test parser::grammar_skeleton::function_definition_missing_body_fails ... ok
[INFO] [stdout] test parser::grammar_skeleton::function_definition_requires_compound_body ... ok
[INFO] [stdout] test parser::grammar_skeleton::io_location_redirect_is_gated_by_option ... ok
[INFO] [stdout] test parser::grammar_skeleton::function_definition_parses_subshell_body_after_linebreak ... ok
[INFO] [stdout] test parser::grammar_skeleton::if_elif_else_compound_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::list_with_separators_parses_multiple_and_ors ... ok
[INFO] [stdout] test parser::grammar_skeleton::io_number_redirect_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::missing_esac_fails ... ok
[INFO] [stdout] test parser::grammar_skeleton::missing_then_in_if_fails ... ok
[INFO] [stdout] test parser::grammar_skeleton::node_limit_failure_stays_deterministic_on_valid_parse_path ... ok
[INFO] [stdout] test parser::grammar_skeleton::non_name_function_head_is_not_accepted ... ok
[INFO] [stdout] test parser::grammar_skeleton::one_word_simple_command_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::missing_do_after_while_fails ... ok
[INFO] [stdout] test parser::grammar_skeleton::pipeline_negation_sets_flag ... ok
[INFO] [stdout] test parser::grammar_skeleton::parse_complete_command_is_boundary_safe_across_multiple_commands ... ok
[INFO] [stdout] test parser::grammar_skeleton::pipeline_parses_two_commands ... ok
[INFO] [stdout] test parser::grammar_skeleton::redirect_order_is_preserved_across_prefix_and_suffix ... ok
[INFO] [stdout] test parser::grammar_skeleton::redirect_missing_target_reports_deterministic_error ... ok
[INFO] [stdout] test parser::grammar_skeleton::redirect_prefix_form_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::redirect_target_word_can_be_numeric ... ok
[INFO] [stdout] test parser::grammar_skeleton::redirection_in_command_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::parse_program_handles_leading_and_trailing_blank_lines ... ok
[INFO] [stdout] test parser::grammar_skeleton::unterminated_subshell_and_brace_group_fail ... ok
[INFO] [stdout] test parser::grammar_skeleton::subshell_compound_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::word_sequence_simple_command_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::while_compound_parses ... ok
[INFO] [stdout] test parser::grammar_skeleton::until_compound_parses ... ok
[INFO] [stdout] test parser::negative_reserved_words::standalone_reserved_words_fail_at_top_level ... ok
[INFO] [stdout] test parser::parser_entrypoints::boundary_keeps_second_command_after_function_definition ... ok
[INFO] [stdout] test parser::negative_reserved_words::invalid_reserved_words_in_compound_contexts_fail ... ok
[INFO] [stdout] test parser::negative_reserved_words::reserved_lexemes_in_suffix_position_stay_plain_words ... ok
[INFO] [stdout] test parser::parser_entrypoints::boundary_preserves_following_command_after_trailing_ampersand_newline ... ok
[INFO] [stdout] test parser::parser_entrypoints::boundary_preserves_following_command_after_trailing_semicolon_newline ... ok
[INFO] [stdout] test parser::parser_entrypoints::boundary_stepping_is_stable_with_leading_and_trailing_blank_lines ... ok
[INFO] [stdout] test parser::parser_entrypoints::interactive_trailing_operators_return_specific_need_more_reason ... ok
[INFO] [stdout] test parser::parser_entrypoints::max_nesting_guard_allows_depth_within_limit ... ok
[INFO] [stdout] test parser::parser_entrypoints::max_nesting_guard_rejects_pathological_depth ... ok
[INFO] [stdout] test parser::parser_entrypoints::mixed_validity_interactive_returns_complete_then_need_more ... ok
[INFO] [stdout] test parser::parser_entrypoints::mixed_validity_non_interactive_returns_complete_then_error ... ok
[INFO] [stdout] test parser::parser_entrypoints::non_interactive_incomplete_constructs_still_return_unexpected_end_of_input ... ok
[INFO] [stdout] test parser::parser_entrypoints::parse_complete_command_returns_complete_for_simple_command ... ok
[INFO] [stdout] test parser::parser_entrypoints::parse_complete_command_returns_end_of_input_on_empty_source ... ok
[INFO] [stdout] test parser::parser_entrypoints::parse_complete_command_returns_error_in_non_interactive_mode ... ok
[INFO] [stdout] test parser::parser_entrypoints::parse_complete_command_returns_need_more_input_in_interactive_mode ... ok
[INFO] [stdout] test parser::parser_entrypoints::parse_complete_command_sequence_matches_parse_program_order ... ok
[INFO] [stdout] test parser::parser_entrypoints::stress_deep_nesting_with_default_limit_is_stable ... ok
[INFO] [stdout] test parser::parser_entrypoints::interactive_incomplete_constructs_return_specific_need_more_reason ... ok
[INFO] [stdout] test ir::scaling::pool_interning_scales_linearly ... FAILED
[INFO] [stdout] test ir::scaling::branch_patching_scales_linearly ... FAILED
[INFO] [stdout] test ir::scaling::full_lowering_scales_linearly ... FAILED
[INFO] [stdout] test lexer::property_cursor_invariants::rollback_is_idempotent_for_same_checkpoint ... ok
[INFO] [stdout] test parser::token_stream::eof_is_idempotent_for_peek_and_next ... ok
[INFO] [stdout] test parser::token_stream::newline_and_operator_tokens_are_preserved ... ok
[INFO] [stdout] test parser::token_stream::peek_is_non_consuming_and_next_consumes ... ok
[INFO] [stdout] test parser::token_stream::peek_rejects_lookahead_over_limit ... ok
[INFO] [stdout] test parser::token_stream::expect_success_and_failure_are_deterministic ... ok
[INFO] [stdout] test lexer::property_scanning::next_token_handles_lossy_utf8_inputs_without_panicking ... ok
[INFO] [stdout] test vm::dispatch_loop::call_and_ret ... ok
[INFO] [stdout] test vm::dispatch_loop::call_depth_starts_at_zero ... ok
[INFO] [stdout] test vm::dispatch_loop::call_depth_exceeded ... ok
[INFO] [stdout] test vm::dispatch_loop::drop_removes_top ... ok
[INFO] [stdout] test vm::dispatch_loop::dup_duplicates_top ... ok
[INFO] [stdout] test vm::dispatch_loop::dup_preserves_value_type ... ok
[INFO] [stdout] test vm::dispatch_loop::call_preserves_caller_state ... ok
[INFO] [stdout] test vm::dispatch_loop::empty_code_object_returns_exit_status_zero ... ok
[INFO] [stdout] test vm::dispatch_loop::execute_invalid_code_object_entry ... ok
[INFO] [stdout] test parser::token_stream::peek_supports_bounded_lookahead ... ok
[INFO] [stdout] test vm::dispatch_loop::if_else_false_branch ... ok
[INFO] [stdout] test vm::dispatch_loop::if_else_true_branch ... ok
[INFO] [stdout] test vm::dispatch_loop::implicit_return_empty_stack ... ok
[INFO] [stdout] test vm::dispatch_loop::implicit_return_from_callee ... ok
[INFO] [stdout] test vm::dispatch_loop::invalid_code_object_id ... ok
[INFO] [stdout] test parser::parser_entrypoints::stress_mixed_pipeline_and_list_sequences_is_stable ... ok
[INFO] [stdout] test vm::dispatch_loop::invalid_string_id ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_non_zero_not_taken ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_non_zero_taken ... ok
[INFO] [stdout] test vm::dispatch_loop::invalid_symbol_id ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_zero_empty_string ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_zero_nonempty_string ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_zero_not_taken_integer ... ok
[INFO] [stdout] test vm::dispatch_loop::invalid_const_id ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_zero_taken_integer ... ok
[INFO] [stdout] test parser::parser_entrypoints::stress_long_simple_command_stream_is_stable ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_zero_uninitialized ... ok
[INFO] [stdout] test vm::dispatch_loop::local_get_out_of_bounds ... ok
[INFO] [stdout] test vm::dispatch_loop::local_get_uninitialized_returns_uninitialized ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_unconditional ... ok
[INFO] [stdout] test vm::dispatch_loop::local_set_and_get ... ok
[INFO] [stdout] test vm::dispatch_loop::implicit_return_with_stack ... ok
[INFO] [stdout] test vm::dispatch_loop::locals_isolated_across_frames ... ok
[INFO] [stdout] test vm::dispatch_loop::local_overwrite ... ok
[INFO] [stdout] test vm::dispatch_loop::jmp_if_zero_exit_status_zero ... ok
[INFO] [stdout] test vm::dispatch_loop::negate_status_double_negate ... ok
[INFO] [stdout] test vm::dispatch_loop::loop_simulation ... ok
[INFO] [stdout] test ir::property_ir::verify_accepts_all_lowered_programs ... ok
[INFO] [stdout] test vm::dispatch_loop::module_accessor ... ok
[INFO] [stdout] test vm::dispatch_loop::multiple_push_and_drop ... ok
[INFO] [stdout] test vm::dispatch_loop::negate_status_zero_to_one ... ok
[INFO] [stdout] test vm::dispatch_loop::negate_status_string_value ... ok
[INFO] [stdout] test vm::dispatch_loop::nop_returns_exit_status_zero ... ok
[INFO] [stdout] test vm::dispatch_loop::push_const_string ... ok
[INFO] [stdout] test vm::dispatch_loop::negate_status_nonzero_to_zero ... ok
[INFO] [stdout] test vm::dispatch_loop::push_int_negative ... ok
[INFO] [stdout] test vm::dispatch_loop::push_int_returns_value ... ok
[INFO] [stdout] test vm::dispatch_loop::nested_calls ... ok
[INFO] [stdout] test vm::dispatch_loop::negate_status_negative_integer ... ok
[INFO] [stdout] test vm::dispatch_loop::push_const_symbol ... ok
[INFO] [stdout] test vm::dispatch_loop::push_symbol ... ok
[INFO] [stdout] test vm::dispatch_loop::push_const_integer ... ok
[INFO] [stdout] test vm::dispatch_loop::local_set_out_of_bounds ... ok
[INFO] [stdout] test vm::dispatch_loop::stack_underflow_ret ... ok
[INFO] [stdout] test vm::error_types::clone_preserves_equality ... ok
[INFO] [stdout] test vm::dispatch_loop::ret_from_last_frame ... ok
[INFO] [stdout] test vm::dispatch_loop::unimplemented_instruction ... ok
[INFO] [stdout] test vm::error_types::command_not_found_constructor ... ok
[INFO] [stdout] test vm::error_types::command_not_executable_constructor ... ok
[INFO] [stdout] test vm::dispatch_loop::stack_underflow_drop ... ok
[INFO] [stdout] test vm::error_types::different_errors_not_equal ... ok
[INFO] [stdout] test vm::error_types::display_shows_message ... ok
[INFO] [stdout] test vm::error_types::division_by_zero_constructor ... ok
[INFO] [stdout] test vm::error_types::error_kind_display ... ok
[INFO] [stdout] test vm::error_types::implements_std_error ... ok
[INFO] [stdout] test vm::error_types::internal_error_constructor ... ok
[INFO] [stdout] test vm::error_types::invalid_code_object_constructor ... ok
[INFO] [stdout] test vm::error_types::register_out_of_bounds_constructor ... ok
[INFO] [stdout] test vm::error_types::stack_overflow_constructor ... ok
[INFO] [stdout] test vm::error_types::stack_underflow_constructor ... ok
[INFO] [stdout] test vm::error_types::type_mismatch_constructor ... ok
[INFO] [stdout] test vm::frame_ops::advance_pc_after_set_pc ... ok
[INFO] [stdout] test vm::frame_ops::advance_pc_returns_current_and_increments ... ok
[INFO] [stdout] test vm::frame_ops::dup_duplicates_top ... ok
[INFO] [stdout] test vm::frame_ops::dup_overflow_at_max ... ok
[INFO] [stdout] test vm::frame_ops::dup_underflow_on_empty ... ok
[INFO] [stdout] test vm::frame_ops::large_locals_count ... ok
[INFO] [stdout] test vm::dispatch_loop::push_string ... ok
[INFO] [stdout] test vm::frame_ops::max_stack_zero_rejects_all_pushes ... ok
[INFO] [stdout] test vm::frame_ops::mixed_stack_and_register_ops ... ok
[INFO] [stdout] test vm::frame_ops::new_frame_builder_fields_are_none ... ok
[INFO] [stdout] test vm::frame_ops::new_frame_has_correct_code_object_id ... ok
[INFO] [stdout] test vm::frame_ops::new_frame_zero_locals ... ok
[INFO] [stdout] test vm::frame_ops::peek_returns_top_without_removing ... ok
[INFO] [stdout] test vm::frame_ops::peek_underflow_on_empty ... ok
[INFO] [stdout] test vm::frame_ops::push_one_past_max_fails ... ok
[INFO] [stdout] test vm::frame_ops::push_at_exact_max_succeeds ... ok
[INFO] [stdout] test vm::frame_ops::push_overflow_error ... ok
[INFO] [stdout] test vm::frame_ops::push_pop_various_value_types ... ok
[INFO] [stdout] test vm::frame_ops::push_then_pop ... ok
[INFO] [stdout] test vm::frame_ops::new_frame_registers_initialized_to_uninitialized ... ok
[INFO] [stdout] test vm::frame_ops::register_boundary_index ... ok
[INFO] [stdout] test vm::frame_ops::register_get_uninitialized ... ok
[INFO] [stdout] test vm::frame_ops::register_out_of_bounds_get ... ok
[INFO] [stdout] test vm::frame_ops::register_overwrite ... ok
[INFO] [stdout] test vm::frame_ops::register_out_of_bounds_set ... ok
[INFO] [stdout] test vm::frame_ops::register_set_then_get ... ok
[INFO] [stdout] test vm::frame_ops::register_zero_locals_always_fails ... ok
[INFO] [stdout] test vm::frame_ops::pop_underflow_error ... ok
[INFO] [stdout] test vm::frame_ops::set_pc_changes_counter ... ok
[INFO] [stdout] test vm::frame_ops::stack_lifo_order ... ok
[INFO] [stdout] test vm::frame_ops::swap_is_self_inverse ... ok
[INFO] [stdout] test vm::frame_ops::stack_depth_tracking ... ok
[INFO] [stdout] test vm::frame_ops::swap_underflow_with_zero_elements ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_empty_field_list ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_empty_string ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_exit_status ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_exit_status_signal ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_integer ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_integer_masked ... ok
[INFO] [stdout] test vm::frame_ops::new_frame_has_empty_stack ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_field_list ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_integer_negative ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_large_numeric_string ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_uninitialized ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_non_numeric_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_empty_field_list_is_error ... ok
[INFO] [stdout] test vm::value_coercions::as_exit_status_from_numeric_string ... ok
[INFO] [stdout] test vm::frame_ops::new_frame_has_zero_pc ... ok
[INFO] [stdout] test vm::frame_ops::swap_exchanges_top_two ... ok
[INFO] [stdout] test vm::frame_ops::swap_underflow_with_one_element ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_empty_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_exit_status ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_field_list_is_error ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_integer ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_negative_integer ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_non_numeric_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_numeric_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_padded_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_partially_numeric_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_whitespace_string ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_empty_string ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_exit_status_nonzero ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_exit_status_zero ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_field_list ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_i64_max ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_i64_min ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_empty_field_list ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_negative_integer ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_positive_integer ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_single_field_list ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_string ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_uninitialized ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_uninitialized ... ok
[INFO] [stdout] test vm::value_coercions::as_string_from_zero_integer ... ok
[INFO] [stdout] test vm::value_coercions::as_integer_from_negative_string ... ok
[INFO] [stdout] test vm::value_coercions::clone_preserves_equality ... ok
[INFO] [stdout] test vm::value_coercions::default_is_uninitialized ... ok
[INFO] [stdout] test vm::value_coercions::different_values_not_equal ... ok
[INFO] [stdout] test vm::value_coercions::display_matches_as_string ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_exit_status_success ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_field_list_empty ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_field_list_nonempty ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_integer_negative ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_integer_nonzero ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_exit_status_failure ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_exit_status_signal ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_integer_zero ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_string_nonempty ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_string_empty ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_string_zero_is_truthy ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_exit_status_nonzero ... ok
[INFO] [stdout] test vm::value_coercions::is_truthy_uninitialized ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_exit_status_zero ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_field_list_empty ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_field_list_nonempty ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_integer_negative ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_integer_nonzero ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_string_empty ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_string_nonempty ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_string_zero_char ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_uninitialized ... ok
[INFO] [stdout] test vm::value_coercions::is_zero_integer_zero ... ok
[INFO] [stdout] test parser::property_robustness::interactive_complete_command_loop_handles_need_more_without_panicking ... ok
[INFO] [stdout] test ir::property_ir::decode_roundtrip_word_count ... ok
[INFO] [stdout] test ir::property_ir::pool_string_dedup_determinism ... ok
[INFO] [stdout] test ir::property_ir::pool_symbol_dedup_determinism ... ok
[INFO] [stdout] test ir::property_ir::encode_is_deterministic ... ok
[INFO] [stdout] test ir::property_ir::lowering_determinism ... ok
[INFO] [stdout] test parser::property_robustness::parse_program_handles_lossy_utf8_inputs_without_panicking ... ok
[INFO] [stdout] test parser::property_robustness::parse_complete_command_loop_terminates_without_panicking ... ok
[INFO] [stdout] test ir::property_ir::pool_const_dedup_determinism ... ok
[INFO] [stdout] test ir::property_ir::encode_accepts_all_verified_modules ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- ir::scaling::pool_interning_scales_linearly stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'ir::scaling::pool_interning_scales_linearly' (353) panicked at tests/ir/scaling.rs:45:5:
[INFO] [stdout] scaling violation: large/small time ratio 13.08 exceeds max allowed 12.00 (size_ratio=4.0, tolerance=3.0)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x621e977bacf2 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x621e977bacf2 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x621e977bacf2 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x621e977bacf2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x621e977cc12a - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x621e977cc12a - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x621e97788bb6 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x621e97788bb6 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x621e97799d99 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x621e97799d99 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x621e97799c31 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x621e9760dd1e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x621e9760dd1e - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x621e9779a012 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x621e9779a012 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x621e97799e58 - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x621e97794e79 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x621e9777c7bd - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x621e977d3b1c - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x621e975d7a46 - mod::ir::scaling::assert_linear_scaling::h7a921b31ae1f5f2a
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:45:5
[INFO] [stdout]   20:     0x621e975d8898 - mod::ir::scaling::pool_interning_scales_linearly::hc432fbb9f1cbb428
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:79:5
[INFO] [stdout]   21:     0x621e975d8ac7 - mod::ir::scaling::pool_interning_scales_linearly::{{closure}}::hd40b59e2fb9ef6e6
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:78:36
[INFO] [stdout]   22:     0x621e9750f686 - core::ops::function::FnOnce::call_once::hc0a5591f9debf0cf
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x621e9760dadb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x621e9760dadb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x621e976214ea - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x621e976214ea - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x621e976214ea - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x621e976214ea - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x621e976214ea - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x621e976214ea - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x621e976214ea - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x621e975fb944 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x621e975fb944 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x621e975ff2e2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x621e975ff2e2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x621e975ff2e2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x621e975ff2e2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x621e975ff2e2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x621e975ff2e2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x621e975ff2e2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x621e9778ff3f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x621e9778ff3f - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x7c72cd43daa4 - <unknown>
[INFO] [stdout]   44:     0x7c72cd4caa64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- ir::scaling::branch_patching_scales_linearly stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'ir::scaling::branch_patching_scales_linearly' (350) panicked at tests/ir/scaling.rs:45:5:
[INFO] [stdout] scaling violation: large/small time ratio 52.89 exceeds max allowed 12.00 (size_ratio=4.0, tolerance=3.0)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x621e977bacf2 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x621e977bacf2 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x621e977bacf2 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x621e977bacf2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x621e977cc12a - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x621e977cc12a - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x621e97788bb6 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x621e97788bb6 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x621e97799d99 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x621e97799d99 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x621e97799c31 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x621e9760dd1e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x621e9760dd1e - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x621e9779a012 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x621e9779a012 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x621e97799e58 - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x621e97794e79 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x621e9777c7bd - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x621e977d3b1c - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x621e975d7686 - mod::ir::scaling::assert_linear_scaling::h2f7f065c8e80600f
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:45:5
[INFO] [stdout]   20:     0x621e975d8af8 - mod::ir::scaling::branch_patching_scales_linearly::h20a3e9149b4d99e1
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:100:5
[INFO] [stdout]   21:     0x621e975d8b17 - mod::ir::scaling::branch_patching_scales_linearly::{{closure}}::h1a0249522c9c4f85
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:99:37
[INFO] [stdout]   22:     0x621e975065a6 - core::ops::function::FnOnce::call_once::h089e146913f147e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x621e9760dadb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x621e9760dadb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x621e976214ea - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x621e976214ea - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x621e976214ea - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x621e976214ea - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x621e976214ea - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x621e976214ea - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x621e976214ea - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x621e975fb944 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x621e975fb944 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x621e975ff2e2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x621e975ff2e2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x621e975ff2e2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x621e975ff2e2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x621e975ff2e2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x621e975ff2e2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x621e975ff2e2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x621e9778ff3f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x621e9778ff3f - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x7c72cd43daa4 - <unknown>
[INFO] [stdout]   44:     0x7c72cd4caa64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- ir::scaling::full_lowering_scales_linearly stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'ir::scaling::full_lowering_scales_linearly' (352) panicked at tests/ir/scaling.rs:175:5:
[INFO] [stdout] full lowering scaling violation: ratio 35.54 exceeds max 12.00
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x621e977bacf2 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x621e977bacf2 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x621e977bacf2 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x621e977bacf2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x621e977cc12a - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x621e977cc12a - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x621e97788bb6 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x621e97788bb6 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x621e97799d99 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x621e97799d99 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x621e97799c31 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x621e9760dd1e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x621e9760dd1e - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x621e9779a012 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x621e9779a012 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x621e97799e58 - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x621e97794e79 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x621e9777c7bd - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x621e977d3b1c - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x621e975d881e - mod::ir::scaling::full_lowering_scales_linearly::h3b339b9b34ecc960
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:175:5
[INFO] [stdout]   20:     0x621e975d8867 - mod::ir::scaling::full_lowering_scales_linearly::{{closure}}::h6385906c1e2ffac2
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/ir/scaling.rs:153:35
[INFO] [stdout]   21:     0x621e97511666 - core::ops::function::FnOnce::call_once::hedc050e82e24f960
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x621e9760dadb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x621e9760dadb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   24:     0x621e976214ea - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   25:     0x621e976214ea - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   26:     0x621e976214ea - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x621e976214ea - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x621e976214ea - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x621e976214ea - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   30:     0x621e976214ea - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   31:     0x621e975fb944 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   32:     0x621e975fb944 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x621e975ff2e2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   34:     0x621e975ff2e2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   35:     0x621e975ff2e2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x621e975ff2e2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x621e975ff2e2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x621e975ff2e2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   39:     0x621e975ff2e2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x621e9778ff3f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   41:     0x621e9778ff3f - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   42:     0x7c72cd43daa4 - <unknown>
[INFO] [stdout]   43:     0x7c72cd4caa64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     ir::scaling::branch_patching_scales_linearly
[INFO] [stdout]     ir::scaling::full_lowering_scales_linearly
[INFO] [stdout]     ir::scaling::pool_interning_scales_linearly
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 738 passed; 3 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.77s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test mod`
[INFO] running `Command { std: "docker" "inspect" "e88334b53364d4a782a7cf38f33af284925622e87f6fefed8650b2b5d9f63a50", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e88334b53364d4a782a7cf38f33af284925622e87f6fefed8650b2b5d9f63a50", kill_on_drop: false }`
[INFO] [stdout] e88334b53364d4a782a7cf38f33af284925622e87f6fefed8650b2b5d9f63a50
