[INFO] fetching crate lumen-vm 0.5.0...
[INFO] testing lumen-vm-0.5.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate lumen-vm 0.5.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate lumen-vm 0.5.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate lumen-vm 0.5.0
[INFO] tweaked toml for crates.io crate lumen-vm 0.5.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate lumen-vm 0.5.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate lumen-vm 0.5.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5
[INFO] running `Command { std: "docker" "start" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]    Compiling libc v0.2.181
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling cranelift-isle v0.116.1
[INFO] [stderr]    Compiling cranelift-codegen-shared v0.116.1
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling cranelift-bitset v0.116.1
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling bumpalo v3.19.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling cranelift-control v0.116.1
[INFO] [stderr]    Compiling cc v1.2.55
[INFO] [stderr]    Compiling gimli v0.31.1
[INFO] [stderr]    Compiling anyhow v1.0.101
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling cranelift-codegen-meta v0.116.1
[INFO] [stderr]    Compiling object v0.36.7
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling cranelift-entity v0.116.1
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling webpki-roots v1.0.6
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling cranelift-bforest v0.116.1
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling syn v2.0.115
[INFO] [stderr]    Compiling regalloc2 v0.11.2
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling memmap2 v0.2.3
[INFO] [stderr]    Compiling region v3.0.2
[INFO] [stderr]    Compiling wasmtime-jit-icache-coherence v29.0.1
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling uuid v1.20.0
[INFO] [stderr]    Compiling cranelift-codegen v0.116.1
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling strum_macros v0.26.4
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling strum v0.26.3
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling chrono v0.4.43
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling lumen-runtime v0.5.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling lumen-compiler v0.5.0
[INFO] [stderr]    Compiling rustls v0.23.36
[INFO] [stderr]    Compiling rustls-webpki v0.103.9
[INFO] [stderr]    Compiling cranelift-module v0.116.1
[INFO] [stderr]    Compiling cranelift-native v0.116.1
[INFO] [stderr]    Compiling cranelift-frontend v0.116.1
[INFO] [stderr]    Compiling cranelift-jit v0.116.1
[INFO] [stderr]    Compiling cranelift-object v0.116.1
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling lumen-codegen v0.5.0
[INFO] [stderr]    Compiling lumen-vm v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 2m 15s
[INFO] running `Command { std: "docker" "inspect" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling lumen-vm v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 26.02s
[INFO] running `Command { std: "docker" "inspect" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/lumen-vm/a3d5035549328a21/out/lumen_vm-a3d5035549328a21)
[INFO] [stdout] 
[INFO] [stdout] running 248 tests
[INFO] [stdout] test arena::tests::test_bytes_reserved ... ok
[INFO] [stdout] test arena::tests::test_multiple_chunks ... ok
[INFO] [stdout] test arena::tests::test_reset ... ok
[INFO] [stdout] test arena::tests::test_oversize_allocation ... ok
[INFO] [stdout] test arena::tests::test_basic_allocation ... ok
[INFO] [stdout] test gc::tests::test_pin_unpin ... ok
[INFO] [stdout] test gc::tests::test_forwarded ... ok
[INFO] [stdout] test arena::tests::test_reuse_after_reset ... ok
[INFO] [stdout] test gc::tests::test_max_size_object ... ok
[INFO] [stdout] test gc::tests::test_color_set_get ... ok
[INFO] [stdout] test gc::tests::test_flags_dont_interfere ... ok
[INFO] [stdout] test gc::tests::test_mark_unmark ... ok
[INFO] [stdout] test gc::tests::test_debug_format ... ok
[INFO] [stdout] test arena::tests::test_alignment ... ok
[INFO] [stdout] test immix::tests::test_alloc_multiple_lines ... ok
[INFO] [stdout] test immix::tests::test_alloc_new_block ... ok
[INFO] [stdout] test arena::tests::test_alloc_value_struct ... ok
[INFO] [stdout] test immix::tests::test_block_clear_marks ... ok
[INFO] [stdout] test immix::tests::test_block_count_holes ... ok
[INFO] [stdout] test immix::tests::test_block_find_hole_entire_block ... ok
[INFO] [stdout] test immix::tests::test_block_find_hole_none_when_full ... ok
[INFO] [stdout] test immix::tests::test_block_is_full ... ok
[INFO] [stdout] test gc::tests::test_zero_size_object ... ok
[INFO] [stdout] test arena::tests::test_alloc_value ... ok
[INFO] [stdout] test gc::tests::test_all_type_tags ... ok
[INFO] [stdout] test gc::tests::test_header_size_is_8_bytes ... ok
[INFO] [stdout] test gc::tests::test_new_header_defaults ... ok
[INFO] [stdout] test immix::tests::test_block_find_hole_in_middle ... ok
[INFO] [stdout] test immix::tests::test_block_find_hole_after_marks ... ok
[INFO] [stdout] test arena::tests::test_empty_arena ... ok
[INFO] [stdout] test immix::tests::test_block_mark_unmark ... ok
[INFO] [stdout] test immix::tests::test_block_new_is_empty ... ok
[INFO] [stdout] test immix::tests::test_constants ... ok
[INFO] [stdout] test immix::tests::test_sweep_full_block ... ok
[INFO] [stdout] test immix::tests::test_alloc_alignment ... ok
[INFO] [stdout] test arena::tests::test_zero_chunk_size_panics - should panic ... ok
[INFO] [stdout] test tagged::tests::test_bool_false ... ok
[INFO] [stdout] test immix::tests::test_sweep_partial_block ... ok
[INFO] [stdout] test parity_concurrency::tests::coverage_percent_in_range ... ok
[INFO] [stdout] test tagged::tests::test_equality ... ok
[INFO] [stdout] test tagged::tests::test_bool_true ... ok
[INFO] [stdout] test parity_concurrency::tests::full_checklist_has_at_least_30_items ... ok
[INFO] [stdout] test parity_concurrency::tests::implemented_count_le_total ... ok
[INFO] [stdout] test tagged::tests::test_pointer ... ok
[INFO] [stdout] test tagged::tests::test_debug_format ... ok
[INFO] [stdout] test tagged::tests::test_null ... ok
[INFO] [stdout] test tagged::tests::test_small_int_max ... ok
[INFO] [stdout] test tagged::tests::test_null_pointer_panics - should panic ... ok
[INFO] [stdout] test tagged::tests::test_round_trip_int ... ok
[INFO] [stdout] test tagged::tests::test_small_int_zero ... ok
[INFO] [stdout] test tagged::tests::test_type_discrimination ... ok
[INFO] [stdout] test tlab::tests::test_basic_alloc ... ok
[INFO] [stdout] test tagged::tests::test_unaligned_pointer_panics - should panic ... ok
[INFO] [stdout] test tlab::tests::test_alloc_alignment ... ok
[INFO] [stdout] test tlab::tests::test_fill_up ... ok
[INFO] [stdout] test tlab::tests::test_reset ... ok
[INFO] [stdout] test tlab::tests::test_multiple_small_allocs ... ok
[INFO] [stdout] test tlab::tests::test_new_tlab ... ok
[INFO] [stdout] test tlab::tests::test_zero_capacity_panics - should panic ... ok
[INFO] [stdout] test values::tests::test_as_helpers ... ok
[INFO] [stdout] test values::tests::test_closure_display ... ok
[INFO] [stdout] test values::tests::test_display_pretty_set ... ok
[INFO] [stdout] test tagged::tests::test_small_int_min ... ok
[INFO] [stdout] test values::tests::test_float_equality_normal ... ok
[INFO] [stdout] test tlab::tests::test_debug_display ... ok
[INFO] [stdout] test tagged::tests::test_small_int_negative ... ok
[INFO] [stdout] test tagged::tests::test_small_int_overflow ... ok
[INFO] [stdout] test tagged::tests::test_small_int_positive ... ok
[INFO] [stdout] test tlab::tests::test_alloc_too_large ... ok
[INFO] [stdout] test values::tests::test_interned_string_ordering ... ok
[INFO] [stdout] test values::tests::test_is_truthy_comprehensive ... ok
[INFO] [stdout] test values::tests::test_is_truthy_interned_always_true_without_table ... ok
[INFO] [stdout] test values::tests::test_nan_ordering_is_total_and_stable ... ok
[INFO] [stdout] test values::tests::test_scalars_have_no_heap_indirection ... ok
[INFO] [stdout] test values::tests::test_nan_equality ... ok
[INFO] [stdout] test values::tests::test_value_ordering ... ok
[INFO] [stdout] test values::tests::test_display_pretty_list ... ok
[INFO] [stdout] test values::tests::test_display_pretty_record ... ok
[INFO] [stdout] test values::tests::test_display_pretty_string ... ok
[INFO] [stdout] test values::tests::test_display_pretty_tuple ... ok
[INFO] [stdout] test immix::tests::test_sweep_empty_blocks ... ok
[INFO] [stdout] test immix::tests::test_block_mark_out_of_range - should panic ... ok
[INFO] [stdout] test values::tests::test_scalar_clone_is_bitwise_copy ... ok
[INFO] [stdout] test values::tests::test_values_equal_same_representation ... ok
[INFO] [stdout] test values::tests::test_set_equality ... ok
[INFO] [stdout] test values::tests::test_truthiness ... ok
[INFO] [stdout] test vm::tests::t123_negation_overflow ... ok
[INFO] [stdout] test vm::tests::t123_float_large_mul_produces_infinity ... ok
[INFO] [stdout] test vm::tests::t123_normal_arithmetic_works ... ok
[INFO] [stdout] test values::tests::test_values_equal_nested_maps_with_mixed_strings ... ok
[INFO] [stdout] test values::tests::test_value_size_is_reasonable ... ok
[INFO] [stdout] test vm::tests::t123_operation_specific_messages ... ok
[INFO] [stdout] test vm::tests::t123_power_overflow ... ok
[INFO] [stdout] test vm::tests::t123_division_by_zero_preserved ... ok
[INFO] [stdout] test values::tests::test_values_equal_cross_representation_different_content ... ok
[INFO] [stdout] test strings::tests::test_intern ... ok
[INFO] [stdout] test values::tests::test_values_equal_nested_lists_with_mixed_strings ... ok
[INFO] [stdout] test parity_concurrency::tests::all_ids_are_unique ... ok
[INFO] [stdout] test vm::tests::t123_float_overflow_produces_infinity ... ok
[INFO] [stdout] test vm::tests::t123_addition_overflow ... ok
[INFO] [stdout] test immix::tests::test_alloc_basic ... ok
[INFO] [stdout] test vm::tests::t123_subtraction_overflow ... ok
[INFO] [stdout] test values::tests::test_values_equal_cross_representation_strings ... ok
[INFO] [stdout] test vm::tests::test_arithmetic_overflow_add ... ok
[INFO] [stdout] test vm::tests::test_arithmetic_overflow_sub ... ok
[INFO] [stdout] test vm::tests::test_await_race_block_runtime_deferred_schedule ... ok
[INFO] [stdout] test vm::tests::test_await_parallel_block_runtime ... ok
[INFO] [stdout] test vm::tests::t123_multiplication_overflow ... ok
[INFO] [stdout] test vm::tests::test_builtin_trim_end_no_trailing_whitespace ... ok
[INFO] [stdout] test vm::tests::test_arithmetic_overflow_mul ... ok
[INFO] [stdout] test vm::tests::test_await_parallel_for_block_runtime_deferred_schedule ... ok
[INFO] [stdout] test vm::tests::test_builtin_exp_int ... ok
[INFO] [stdout] test vm::tests::test_builtin_random_int_same_min_max ... ok
[INFO] [stdout] test vm::tests::test_builtin_trim_start_no_leading_whitespace ... ok
[INFO] [stdout] test vm::tests::test_builtin_tan_int ... ok
[INFO] [stdout] test vm::tests::test_builtin_tan_zero ... ok
[INFO] [stdout] test vm::tests::test_builtin_random_int_returns_in_range ... ok
[INFO] [stdout] test vm::tests::test_call_with_unknown_interned_target_returns_runtime_error ... ok
[INFO] [stdout] test vm::tests::test_closure_e2e_make_adder ... ok
[INFO] [stdout] test vm::tests::test_builtin_tan_pi_over_4 ... ok
[INFO] [stdout] test vm::tests::test_closure_multiple_captures ... ok
[INFO] [stdout] test vm::tests::test_builtin_exp_float ... ok
[INFO] [stdout] test vm::tests::test_builtin_exp_one ... ok
[INFO] [stdout] test vm::tests::test_builtin_trim_end ... ok
[INFO] [stdout] test vm::tests::test_builtin_random_int_min_greater_than_max_errors ... ok
[INFO] [stdout] test vm::tests::test_closure_setupval_populates_captures ... ok
[INFO] [stdout] test vm::tests::test_builtin_random_int_negative_range ... ok
[INFO] [stdout] test vm::tests::test_builtin_trim_start ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_toolcall_within_limit_succeeds ... ok
[INFO] [stdout] test vm::tests::test_effect_handler_stack_management ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_untracked_effect_always_allowed ... ok
[INFO] [stdout] test vm::tests::test_div_by_zero_error_type ... ok
[INFO] [stdout] test vm::tests::test_debug_hooks_capture_steps ... ok
[INFO] [stdout] test vm::tests::test_debug_hooks_capture_call_exit ... ok
[INFO] [stdout] test vm::tests::test_debug_hooks_capture_tool_and_schema_events ... ok
[INFO] [stdout] test vm::tests::test_division_by_zero ... ok
[INFO] [stdout] test vm::tests::test_instruction_limit_prevents_infinite_loop ... ok
[INFO] [stdout] test vm::tests::test_hex_decode_non_ascii_returns_null ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_toolcall_enforcement ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_unit_check_and_decrement ... ok
[INFO] [stdout] test vm::tests::test_hex_decode_odd_length_returns_null ... ok
[INFO] [stdout] test vm::tests::test_integer_overflow_add ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_ceil ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_error_message_contains_limit ... ok
[INFO] [stdout] test vm::tests::test_fuel_exhaustion ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_clamp_below ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_toolcall_by_id_prefix ... ok
[INFO] [stdout] test vm::tests::test_fuel_sufficient_for_simple_program ... ok
[INFO] [stdout] test vm::tests::test_handle_push_pop_opcodes ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_ends_with ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_drop ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_enumerate ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_floor ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_pad_left ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_flatten ... ok
[INFO] [stdout] test vm::tests::test_effect_budget_zero_immediately_rejects ... ok
[INFO] [stdout] test vm::tests::test_get_constant_returns_error_on_no_module ... ok
[INFO] [stdout] test vm::tests::test_eq_opcode_interned_vs_owned_string ... ok
[INFO] [stdout] test vm::tests::test_integer_overflow_mul ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_starts_with ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_index_of ... ok
[INFO] [stdout] test vm::tests::test_integer_overflow_sub ... ok
[INFO] [stdout] test vm::tests::test_explicit_future_schedule_not_overridden_by_directive ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_index_of_not_found ... ok
[INFO] [stdout] test vm::tests::test_get_module_string_returns_error_on_no_module ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_pad_right ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_pow ... ok
[INFO] [stdout] test vm::tests::test_machine_graph_current_state_starts_at_initial ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_reverse ... ok
[INFO] [stdout] test vm::tests::test_load_sets_deferred_schedule_from_deterministic_directive ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_round ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_chunk ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_clamp ... ok
[INFO] [stdout] test vm::tests::test_machine_graph_guard_blocks_transition_when_false ... ok
[INFO] [stdout] test vm::tests::test_machine_graph_transition_modulo_by_zero_returns_error ... ok
[INFO] [stdout] test vm::tests::test_match_tuple_destructure_runtime ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_sort ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_zip ... ok
[INFO] [stdout] test vm::tests::test_machine_instances_are_isolated ... ok
[INFO] [stdout] test vm::tests::test_match_type_check_pattern_runtime ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_sqrt ... ok
[INFO] [stdout] test vm::tests::test_memory_runtime_append_recent ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_take ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_unique ... ok
[INFO] [stdout] test vm::tests::test_intrinsic_window ... ok
[INFO] [stdout] test vm::tests::test_machine_graph_guard_and_typed_payload_transition ... ok
[INFO] [stdout] test vm::tests::test_machine_runtime_methods ... ok
[INFO] [stdout] test vm::tests::test_machine_graph_guard_divide_by_zero_returns_error ... ok
[INFO] [stdout] test vm::tests::test_match_record_destructure_runtime ... ok
[INFO] [stdout] test vm::tests::test_machine_graph_start_step_transitions_to_terminal_state ... ok
[INFO] [stdout] test vm::tests::test_match_guard_and_or_runtime ... ok
[INFO] [stdout] test vm::tests::test_match_list_destructure_with_rest_runtime ... ok
[INFO] [stdout] test vm::tests::test_memory_instances_are_isolated ... ok
[INFO] [stdout] test vm::tests::test_orchestration_vm_level_fan_out ... ok
[INFO] [stdout] test vm::tests::test_perform_matches_among_multiple_handlers ... ok
[INFO] [stdout] test vm::tests::test_perform_matches_correct_handler ... ok
[INFO] [stdout] test vm::tests::test_perform_unhandled_effect_error ... ok
[INFO] [stdout] test vm::tests::test_modulo_by_zero ... ok
[INFO] [stdout] test vm::tests::test_memory_runtime_upsert_get ... ok
[INFO] [stdout] test vm::tests::test_pipeline_declarative_stages_generate_run_semantics ... ok
[INFO] [stdout] test vm::tests::test_negative_shift_out_of_range ... ok
[INFO] [stdout] test vm::tests::test_pipeline_three_stages ... ok
[INFO] [stdout] test vm::tests::test_pipeline_vm_level_empty_stages ... ok
[INFO] [stdout] test vm::tests::test_pipeline_vm_level_stage_chaining ... ok
[INFO] [stdout] test vm::tests::test_policy_domain_constraint ... ok
[INFO] [stdout] test vm::tests::test_policy_generic_max_constraint_requires_integer_constraint ... ok
[INFO] [stdout] test vm::tests::test_process_constructor_and_method_dispatch ... ok
[INFO] [stdout] test vm::tests::test_process_static_dot_dispatch_via_constructor ... ok
[INFO] [stdout] test vm::tests::test_policy_generic_max_constraint_works_for_any_max_key ... ok
[INFO] [stdout] test vm::tests::test_pow_exponent_out_of_range ... ok
[INFO] [stdout] test vm::tests::test_policy_generic_max_constraint_allows_within_limit ... ok
[INFO] [stdout] test vm::tests::test_mod_by_zero_error_type ... ok
[INFO] [stdout] test vm::tests::test_policy_timeout_ms_constraint ... ok
[INFO] [stdout] test vm::tests::test_provider_registry_missing_provider_returns_error ... ok
[INFO] [stdout] test vm::tests::test_policy_generic_max_constraint_rejects_over_limit ... ok
[INFO] [stdout] test vm::tests::test_provider_registry_dispatches_tool_call ... ok
[INFO] [stdout] test vm::tests::test_race_builtin_returns_first_value ... ok
[INFO] [stdout] test vm::tests::test_register_oob_reports_invalid_operand_in_hot_path ... FAILED
[INFO] [stdout] test vm::tests::test_select_builtin_returns_first_non_null ... ok
[INFO] [stdout] test vm::tests::test_register_oob_reports_invalid_call_argument_span ... FAILED
[INFO] [stdout] test vm::tests::test_string_slice_utf8_safe ... ok
[INFO] [stdout] test vm::tests::test_timeout_builtin_returns_value_for_non_future ... ok
[INFO] [stdout] test vm::tests::test_tool_policy_violation_blocks_dispatch ... ok
[INFO] [stdout] test vm::tests::test_shift_out_of_range ... ok
[INFO] [stdout] test vm::tests::test_spawn_await_deferred_fifo_schedule ... ok
[INFO] [stdout] test vm::tests::test_spawn_await_failed_future_propagates_error ... ok
[INFO] [stdout] test vm::tests::test_no_fuel_set_runs_normally ... ok
[INFO] [stdout] test vm::tests::test_tool_alias_call_dispatches_to_runtime_tool ... ok
[INFO] [stdout] test vm::tests::test_spawn_await_eager_schedule ... ok
[INFO] [stdout] test vm::tests::test_parallel_builtin_collects_values ... ok
[INFO] [stdout] test vm::tests::test_stack_trace_capture ... ok
[INFO] [stdout] test vm::tests::test_stack_overflow_detection ... ok
[INFO] [stdout] test vm::tests::test_vm_add ... ok
[INFO] [stdout] test vm::tests::test_vm_comparison ... ok
[INFO] [stdout] test vm::tests::test_vm_return_42 ... ok
[INFO] [stdout] test vm::tests::test_vm_bitwise ... ok
[INFO] [stdout] test vm::tests::test_vm_print ... ok
[INFO] [stdout] test vm::tests::test_value_is_truthy_interned_empty_string ... ok
[INFO] [stdout] test vm::tests::test_trace_ref_defaults_to_doc_hash_and_resets_each_execute ... ok
[INFO] [stdout] test vm::tests::test_toset_intrinsic_deduplicates ... ok
[INFO] [stdout] test vm::tests::test_vote_builtin_returns_majority ... ok
[INFO] [stdout] test vm::tests::test_trace_ref_uses_explicit_trace_id_and_monotonic_seq ... ok
[INFO] [stdout] test vm::tests::test_vm_append ... ok
[INFO] [stdout] test vm::tests::test_vm_set ... ok
[INFO] [stdout] test vm::tests::test_vm_string_concat ... ok
[INFO] [stdout] test vm::tests::test_vm_tuple ... ok
[INFO] [stdout] test vm::tests::test_perform_compiles ... ok
[INFO] [stdout] test vm::tests::test_set_index_on_non_indexable_type_errors ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- vm::tests::test_register_oob_reports_invalid_operand_in_hot_path stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'vm::tests::test_register_oob_reports_invalid_operand_in_hot_path' (2625) panicked at src/vm/mod.rs:6067:14:
[INFO] [stdout] invalid register operand should fail: Null
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x594335e62d81 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x594335e7c8ea - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x594335e6844c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x594335e3b2f6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x594335e59fd9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x594335af2040 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x594335e5a192 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x594335e3b3a2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x594335e33359 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x594335e3c3cd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x594335e7d0cc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x594335e7cea2 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x59433588f426 - <lumen_vm[507981897c9aa46a]::vm::tests::test_register_oob_reports_invalid_operand_in_hot_path::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x594335ae548b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   14:     0x594335af2665 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x594335aebf54 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x594335af56f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   17:     0x594335e6178f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x771fea648dfa - <unknown>
[INFO] [stdout]   19:     0x771fea6dc3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- vm::tests::test_register_oob_reports_invalid_call_argument_span stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'vm::tests::test_register_oob_reports_invalid_call_argument_span' (2624) panicked at src/vm/mod.rs:6108:14:
[INFO] [stdout] invalid call argument span should fail: Int(0)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x594335e62d81 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x594335e7c8ea - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x594335e6844c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x594335e3b2f6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x594335e59fd9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x594335af2040 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x594335e5a192 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x594335e3b3a2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x594335e33359 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x594335e3c3cd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x594335e7d0cc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x594335e7cea2 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x59433588e369 - <lumen_vm[507981897c9aa46a]::vm::tests::test_register_oob_reports_invalid_call_argument_span::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x594335ae548b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   14:     0x594335af2665 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x594335aebf54 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x594335af56f2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   17:     0x594335e6178f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x771fea648dfa - <unknown>
[INFO] [stdout]   19:     0x771fea6dc3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     vm::tests::test_register_oob_reports_invalid_call_argument_span
[INFO] [stdout]     vm::tests::test_register_oob_reports_invalid_operand_in_hot_path
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 246 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5", kill_on_drop: false }`
[INFO] [stdout] 0eb3a2cd1ed3d87b67a271f8866777856863b0ee4b44c92f9e052240078355f5
