[INFO] fetching crate aegis_vm 0.2.51...
[INFO] testing aegis_vm-0.2.51 against master#2fd6efc32704647e64d3d646d21c4c68eae100e4 for pr-149852-1
[INFO] extracting crate aegis_vm 0.2.51 into /workspace/builds/worker-0-tc1/source
[INFO] removed /workspace/builds/worker-0-tc1/source/.cargo/config.toml
[INFO] started tweaking crates.io crate aegis_vm 0.2.51
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate aegis_vm 0.2.51
[INFO] tweaked toml for crates.io crate aegis_vm 0.2.51 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate aegis_vm 0.2.51 on toolchain 2fd6efc32704647e64d3d646d21c4c68eae100e4
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate aegis_vm 0.2.51 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" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded spin v0.10.0
[INFO] [stderr]   Downloaded aegis_vm_macro v0.2.51
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 12f93d94be8af270d73918b3cb3be73638b4fdf1f67fd8187f8d3866c3700098
[INFO] running `Command { std: "docker" "start" "-a" "12f93d94be8af270d73918b3cb3be73638b4fdf1f67fd8187f8d3866c3700098", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "12f93d94be8af270d73918b3cb3be73638b4fdf1f67fd8187f8d3866c3700098", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "12f93d94be8af270d73918b3cb3be73638b4fdf1f67fd8187f8d3866c3700098", kill_on_drop: false }`
[INFO] [stdout] 12f93d94be8af270d73918b3cb3be73638b4fdf1f67fd8187f8d3866c3700098
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1cae7c3f493ce1dfb1c396be76a86adae882b73e7d787973aa9893797ddcb0ee
[INFO] running `Command { std: "docker" "start" "-a" "1cae7c3f493ce1dfb1c396be76a86adae882b73e7d787973aa9893797ddcb0ee", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.177
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling spin v0.10.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling inout v0.1.4
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling cipher v0.4.4
[INFO] [stderr]    Compiling universal-hash v0.5.1
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling aead v0.5.2
[INFO] [stderr]    Compiling polyval v0.6.2
[INFO] [stderr]    Compiling ghash v0.5.1
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling aes v0.8.4
[INFO] [stderr]    Compiling ctr v0.9.2
[INFO] [stderr]    Compiling aes-gcm v0.10.3
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling aegis_vm v0.2.51 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling aegis_vm_macro v0.2.51
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.25s
[INFO] running `Command { std: "docker" "inspect" "1cae7c3f493ce1dfb1c396be76a86adae882b73e7d787973aa9893797ddcb0ee", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1cae7c3f493ce1dfb1c396be76a86adae882b73e7d787973aa9893797ddcb0ee", kill_on_drop: false }`
[INFO] [stdout] 1cae7c3f493ce1dfb1c396be76a86adae882b73e7d787973aa9893797ddcb0ee
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cf9a7df79f89cb929e7f0ed6c00861f8b56ceb678a03d6dda440a2828aad0de9
[INFO] running `Command { std: "docker" "start" "-a" "cf9a7df79f89cb929e7f0ed6c00861f8b56ceb678a03d6dda440a2828aad0de9", kill_on_drop: false }`
[INFO] [stderr]    Compiling aegis_vm v0.2.51 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling aegis_vm_macro v0.2.51
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 16s
[INFO] running `Command { std: "docker" "inspect" "cf9a7df79f89cb929e7f0ed6c00861f8b56ceb678a03d6dda440a2828aad0de9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cf9a7df79f89cb929e7f0ed6c00861f8b56ceb678a03d6dda440a2828aad0de9", kill_on_drop: false }`
[INFO] [stdout] cf9a7df79f89cb929e7f0ed6c00861f8b56ceb678a03d6dda440a2828aad0de9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] fe3c593ff0cb8fe6be6c63f8a0c98475905d643aa3c13b7c619cc355dfc8cc7d
[INFO] running `Command { std: "docker" "start" "-a" "fe3c593ff0cb8fe6be6c63f8a0c98475905d643aa3c13b7c619cc355dfc8cc7d", kill_on_drop: false }`
[INFO] [stderr]    Compiling aegis_vm v0.2.51 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 31s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/aegis_vm-d9ae3e11e1113f02)
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test handlers::string::tests::test_string_constants ... ok
[INFO] [stdout] test handlers::vector::tests::test_vec_header_constants ... ok
[INFO] [stdout] test integrity::tests::test_fnv1a_hash_deterministic ... ok
[INFO] [stdout] test integrity::tests::test_fnv1a_hash_different_inputs ... ok
[INFO] [stdout] test integrity::tests::test_empty_bytecode ... ok
[INFO] [stdout] test integrity::tests::test_integrity_error_details ... ok
[INFO] [stdout] test integrity::tests::test_integrity_table_creation ... ok
[INFO] [stdout] test integrity::tests::test_integrity_verify_tampered ... ok
[INFO] [stdout] test integrity::tests::test_integrity_verify_valid ... ok
[INFO] [stdout] test smc::tests::test_encrypt_decrypt_symmetric ... ok
[INFO] [stdout] test smc::tests::test_instruction_length ... ok
[INFO] [stdout] test smc::tests::test_key_at_deterministic ... ok
[INFO] [stdout] test state::tests::test_dynamic_registers ... ok
[INFO] [stdout] test integrity::tests::test_small_bytecode ... ok
[INFO] [stdout] test state::tests::test_heap_allocation ... ok
[INFO] [stdout] test state::tests::test_heap_free_basic ... ok
[INFO] [stdout] test state::tests::test_heap_free_invalid ... ok
[INFO] [stdout] test state::tests::test_heap_limit ... ok
[INFO] [stdout] test state::tests::test_heap_free_reuse ... ok
[INFO] [stdout] test state::tests::test_reset ... ok
[INFO] [stdout] test string_obfuscation::tests::test_decrypt_roundtrip ... ok
[INFO] [stdout] test string_obfuscation::tests::test_different_positions_different_keys ... ok
[INFO] [stdout] test state::tests::test_heap_free_merge_adjacent ... ok
[INFO] [stdout] test whitebox::generator::tests::test_random_permutation ... ok
[INFO] [stdout] test whitebox::cipher::tests::test_shift_rows ... ok
[INFO] [stdout] test whitebox::generator::tests::test_seeded_rng_deterministic ... ok
[INFO] [stdout] test whitebox::sbox::tests::test_gf_mul ... ok
[INFO] [stdout] test state::tests::test_heap_free_space ... ok
[INFO] [stdout] test whitebox::sbox::tests::test_key_expansion ... ok
[INFO] [stdout] test whitebox::cipher::tests::test_mix_columns ... ok
[INFO] [stdout] test whitebox::tables::tests::test_bijection4_identity ... ok
[INFO] [stdout] test whitebox::tables::tests::test_bijection8_identity ... ok
[INFO] [stdout] test string_obfuscation::tests::test_different_ids_different_keys ... ok
[INFO] [stdout] test state::tests::test_heap_read_write ... ok
[INFO] [stdout] test whitebox::generator::tests::test_generate_tables_lite ... ok
[INFO] [stdout] test whitebox::sbox::tests::test_sbox_inverse ... ok
[INFO] [stdout] test whitebox::tables::tests::test_mixing_bijection_identity ... ok
[INFO] [stdout] test whitebox::tables::tests::test_whitebox_tables_memory ... ok
[INFO] [stdout] test whitebox::generator::tests::test_seeded_rng_different_seeds ... ok
[INFO] [stdout] test whitebox::tables::tests::test_whitebox_tables_lite_memory ... ok
[INFO] [stdout] test whitebox::cipher::tests::test_whitebox_encrypt_lite_roundtrip ... ok
[INFO] [stdout] test whitebox::generator::tests::test_generate_tables ... ok
[INFO] [stdout] test whitebox::cipher::tests::test_whitebox_different_plaintexts ... ok
[INFO] [stdout] test whitebox::cipher::tests::test_whitebox_deterministic ... ok
[INFO] [stdout] test whitebox::cipher::tests::test_whitebox_avalanche ... ok
[INFO] [stdout] test whitebox::generator::tests::test_tables_deterministic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.34s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/anti_analysis.rs (/opt/rustwide/target/debug/deps/anti_analysis-9572813f4e058210)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test test_debug_arithmetic ... ok
[INFO] [stdout] test test_paranoid_mixed_ops ... ok
[INFO] [stdout] test test_paranoid_arithmetic ... ok
[INFO] [stdout] test test_mixed_ops ... ok
[INFO] [stdout] test test_mixed_ops_equivalence ... ok
[INFO] [stdout] test test_stress_mixed_ops ... ok
[INFO] [stdout] test test_inc_chain ... ok
[INFO] [stdout] test test_stress_inc_chain ... ok
[INFO] [stdout] test test_shift_ops ... ok
[INFO] [stdout] test test_dec_chain ... ok
[INFO] [stdout] test test_standard_arithmetic ... ok
[INFO] [stdout] test test_cross_level_equivalence ... ok
[INFO] [stdout] test test_stress_dec_chain ... ok
[INFO] [stdout] test test_bitwise_not_chain ... ok
[INFO] [stdout] test test_edge_cases ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 21.41s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/build_config.rs (/opt/rustwide/target/debug/deps/build_config-ec624cae68aee1b6)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_build_id_derivation_consistent ... ok
[INFO] [stdout] test test_build_timestamp_reasonable ... ok
[INFO] [stderr]      Running tests/crypto.rs (/opt/rustwide/target/debug/deps/crypto-d5cd2cbefcb5540b)
[INFO] [stdout] test test_protection_level_valid ... ok
[INFO] [stdout] test test_watermark_derived_from_inputs ... ok
[INFO] [stdout] test test_build_seed_not_zero ... ok
[INFO] [stdout] test test_customer_id_exists ... ok
[INFO] [stdout] test test_build_id_not_zero ... ok
[INFO] [stdout] test test_git_commit_exists ... ok
[INFO] [stdout] test test_watermark_parts_match ... ok
[INFO] [stdout] test test_build_seq_exists ... ok
[INFO] [stdout] test test_watermark_not_zero ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test test_key_derivation ... ok
[INFO] [stdout] test test_hmac_verification ... ok
[INFO] [stdout] test test_build_id_derivation ... ok
[INFO] [stdout] test test_nonce_derivation ... ok
[INFO] [stdout] test test_encrypt_large ... ok
[INFO] [stdout] test test_nonce_uniqueness ... ok
[INFO] [stdout] test test_tampered_ciphertext_fails ... ok
[INFO] [stdout] test test_encrypt_empty ... ok
[INFO] [stdout] test test_encrypt_decrypt ... ok
[INFO] [stderr]      Running tests/division.rs (/opt/rustwide/target/debug/deps/division-8bfb57fa765b442c)
[INFO] [stdout] test test_different_seeds_different_encryption ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 14.82s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test test_gcd_step ... ok
[INFO] [stdout] test test_large_numbers ... ok
[INFO] [stdout] test test_division_by_zero ... ok
[INFO] [stdout] test test_unsigned_division ... ok
[INFO] [stdout] test test_modulo_by_zero ... ok
[INFO] [stdout] test test_divmod_combined ... ok
[INFO] [stderr]      Running tests/encrypted_execution.rs (/opt/rustwide/target/debug/deps/encrypted_execution-9a0cba7de0c8776c)
[INFO] [stdout] test test_unsigned_modulo ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_protection_level_flags ... ok
[INFO] [stdout] test test_encrypted_with_input ... ok
[INFO] [stdout] test test_tampered_encrypted_bytecode_fails ... ok
[INFO] [stdout] test test_encrypted_simple_addition ... ok
[INFO] [stdout] test test_plaintext_mode ... ok
[INFO] [stdout] test test_encrypted_loop ... ok
[INFO] [stdout] test test_package_serialization ... ok
[INFO] [stdout] test test_encrypted_complex_arithmetic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.79s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fnv1a_hash.rs (/opt/rustwide/target/debug/deps/fnv1a_hash-83c1b28781efd80a)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_fnv1a_32_consistency ... ok
[INFO] [stdout] test test_fnv1a_bytecode_loop ... ok
[INFO] [stdout] test test_simple_execution ... ok
[INFO] [stdout] test test_fnv1a_bytecode ... ok
[INFO] [stdout] test test_fnv1a_consistency ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/free_list_allocator.rs (/opt/rustwide/target/debug/deps/free_list_allocator-c9075493b992a43e)
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test test_double_free_protection ... ok
[INFO] [stdout] test test_free_alloc_free_cycle ... ok
[INFO] [stdout] test test_interleaved_alloc_free ... ok
[INFO] [stdout] test test_loop_simulation_with_free ... ok
[INFO] [stdout] test test_memory_savings_comparison ... ok
[INFO] [stdout] test test_repeated_alloc_free_no_growth ... ok
[INFO] [stdout] test test_with_free_heap_reuses ... ok
[INFO] [stdout] test test_without_free_heap_grows ... ok
[INFO] [stdout] test test_write_to_reused_block ... ok
[INFO] [stdout] test test_merge_adjacent_blocks ... ok
[INFO] [stdout] test test_double_free_after_realloc ... ok
[INFO] [stdout] test test_free_and_reuse_same_address ... ok
[INFO] [stderr]      Running tests/handler_mutation_integration.rs (/opt/rustwide/target/debug/deps/handler_mutation_integration-79268c1016242dd8)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test test_vm_add_overflow ... ok
[INFO] [stdout] test test_vm_and_through_execute ... ok
[INFO] [stdout] test test_vm_inc_dec_chain ... ok
[INFO] [stdout] test test_vm_inc_through_execute ... ok
[INFO] [stdout] test test_vm_bitwise_chain ... ok
[INFO] [stdout] test test_vm_complex_arithmetic_expression ... ok
[INFO] [stdout] test test_vm_and_with_zero ... ok
[INFO] [stdout] test test_vm_add_through_execute ... ok
[INFO] [stdout] test test_vm_dec_through_execute ... ok
[INFO] [stdout] test test_vm_factorial_with_mutated_mul ... ok
[INFO] [stdout] test test_vm_not_double_inversion ... ok
[INFO] [stdout] test test_vm_not_through_execute ... ok
[INFO] [stdout] test test_vm_or_through_execute ... ok
[INFO] [stdout] test test_vm_sub_through_execute ... ok
[INFO] [stdout] test test_vm_sub_underflow ... ok
[INFO] [stdout] test test_vm_xor_self_zero ... ok
[INFO] [stdout] test test_vm_xor_through_execute ... ok
[INFO] [stdout] test test_vm_or_with_zero ... ok
[INFO] [stdout] test test_vm_sum_loop_with_mutated_add ... ok
[INFO] [stdout] test test_vm_fibonacci_with_mutated_handlers ... ok
[INFO] [stdout] test test_vm_mul_through_execute ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/heap_ops.rs (/opt/rustwide/target/debug/deps/heap_ops-d0ca43c73fe15fb0)
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 43 tests
[INFO] [stdout] test allocation::test_alignment_7_bytes ... ok
[INFO] [stdout] test allocation::test_alignment_to_8_bytes ... ok
[INFO] [stdout] test allocation::test_alignment_9_bytes ... ok
[INFO] [stdout] test allocation::test_basic_alloc_returns_zero ... ok
[INFO] [stdout] test allocation::test_large_allocation ... ok
[INFO] [stdout] test allocation::test_sequential_allocs_increment ... ok
[INFO] [stdout] test allocation::test_zero_size_alloc ... ok
[INFO] [stdout] test data_structures::test_array_sum ... ok
[INFO] [stdout] test data_structures::test_byte_pattern ... ok
[INFO] [stdout] test data_structures::test_counter_increment ... ok
[INFO] [stdout] test data_structures::test_linked_blocks ... ok
[INFO] [stdout] test edge_cases::test_all_zeros ... ok
[INFO] [stdout] test edge_cases::test_overwrite_same_location ... ok
[INFO] [stdout] test edge_cases::test_interleaved_alloc_and_write ... ok
[INFO] [stdout] test edge_cases::test_read_at_exact_boundary ... ok
[INFO] [stdout] test error_handling::test_load_past_allocation ... ok
[INFO] [stdout] test error_handling::test_store_unallocated_address ... ok
[INFO] [stdout] test error_handling::test_u16_partial_overflow ... ok
[INFO] [stdout] test heap_free::test_free_is_noop ... ok
[INFO] [stdout] test heap_free::test_free_reuse ... ok
[INFO] [stdout] test heap_size::test_empty_heap_size ... ok
[INFO] [stdout] test heap_size::test_size_after_single_alloc ... ok
[INFO] [stdout] test heap_size::test_size_accumulation ... ok
[INFO] [stdout] test integration::test_heap_with_input ... ok
[INFO] [stdout] test integration::test_heap_with_loops ... ok
[INFO] [stdout] test patterns::test_sequential_values ... ok
[INFO] [stdout] test patterns::test_fill_pattern ... ok
[INFO] [stdout] test store_load::test_mixed_size_operations ... ok
[INFO] [stdout] test store_load::test_u8_boundary_values ... ok
[INFO] [stdout] test store_load::test_u64_max_value ... ok
[INFO] [stdout] test error_handling::test_store_past_allocation ... ok
[INFO] [stdout] test store_load::test_u16_store_load ... ok
[INFO] [stdout] test store_load::test_u64_store_load ... ok
[INFO] [stdout] test store_load::test_u32_store_load ... ok
[INFO] [stdout] test store_load::test_u8_store_load ... ok
[INFO] [stdout] test error_handling::test_load_unallocated_address ... ok
[INFO] [stdout] test stress::test_alternating_sizes ... ok
[INFO] [stdout] test stress::test_100_small_allocations ... ok
[INFO] [stdout] test stress::test_varying_allocation_sizes ... ok
[INFO] [stdout] test stress::test_1000_store_load_cycles ... ok
[INFO] [stdout] test error_handling::test_u64_partial_overflow ... ok
[INFO] [stdout] test edge_cases::test_address_arithmetic ... ok
[INFO] [stderr]      Running tests/if_conditions.rs (/opt/rustwide/target/debug/deps/if_conditions-e613ad734ab83ab9)
[INFO] [stdout] test error_handling::test_u32_partial_overflow ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 43 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test test_count_with_if ... ok
[INFO] [stdout] test test_diff_names ... ok
[INFO] [stdout] test test_compare_vars_in_loop ... ok
[INFO] [stdout] test test_if_else_expression_direct ... ok
[INFO] [stdout] test test_if_else_in_loop ... ok
[INFO] [stdout] test test_identity ... ok
[INFO] [stdout] test test_if_else_expression_max ... ok
[INFO] [stdout] test test_if_else_nested_clamp ... ok
[INFO] [stdout] test test_iter_counts ... ok
[INFO] [stdout] test test_minimal_loop ... ok
[INFO] [stdout] test test_simple_if_in_loop ... ok
[INFO] [stdout] test test_nested_if_in_loop ... ok
[INFO] [stdout] test test_threat_penalty_debug ... ok
[INFO] [stdout] test test_timing_v2 ... ok
[INFO] [stdout] test test_let_inside_if_in_loop ... ok
[INFO] [stdout] test test_timing_v3 ... ok
[INFO] [stdout] test test_let_in_both_branches ... ok
[INFO] [stdout] test test_check_zero ... ok
[INFO] [stdout] test test_timing_deltas_debug ... ok
[INFO] [stdout] test test_exact_v2 ... ok
[INFO] [stdout] test test_v2_with_8_iter ... ok
[INFO] [stdout] test test_let_if_expr_inside_if_in_loop ... ok
[INFO] [stdout] test test_threat_penalty_standard ... ok
[INFO] [stdout] test test_threat_penalty_paranoid ... ok
[INFO] [stdout] test test_all_protection_levels_equivalent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.85s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integrity.rs (/opt/rustwide/target/debug/deps/integrity-2312c945447d6b61)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test test_compute_hash_consistency ... ok
[INFO] [stdout] test test_exact_region_size_bytecode ... ok
[INFO] [stdout] test test_debug_no_integrity_works ... ok
[INFO] [stdout] test test_integrity_detects_tampering ... ok
[INFO] [stdout] test test_integrity_table_api ... ok
[INFO] [stdout] test test_verify_hash ... ok
[INFO] [stdout] test test_small_bytecode_integrity ... ok
[INFO] [stdout] test test_empty_bytecode_integrity ... ok
[INFO] [stdout] test test_multiple_regions_bytecode ... ok
[INFO] [stdout] test test_paranoid_integrity_works ... ok
[INFO] [stdout] test test_standard_integrity_works ... ok
[INFO] [stdout] test test_all_levels_produce_same_result ... ok
[INFO] [stderr]      Running tests/loop_edge_cases.rs (/opt/rustwide/target/debug/deps/loop_edge_cases-336199846c9b1020)
[INFO] [stdout] test test_multi_region_function ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 9.35s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test test_break_first_thing ... ok
[INFO] [stdout] test test_break_with_struct_cleanup ... ok
[INFO] [stdout] test test_continue_skip_all ... ok
[INFO] [stdout] test test_break_with_tuple_cleanup ... ok
[INFO] [stdout] test test_nested_for_empty_both ... ok
[INFO] [stdout] test test_nested_while_empty_inner ... ok
[INFO] [stdout] test test_multiple_breaks ... ok
[INFO] [stdout] test test_nested_break_cleanup ... ok
[INFO] [stdout] test test_return_with_deep_cleanup ... ok
[INFO] [stdout] test test_while_empty_body_runs_once ... ok
[INFO] [stdout] test test_while_only_let_stmt ... ok
[INFO] [stdout] test test_loop_immediate_break_empty ... ok
[INFO] [stdout] test test_nested_continue_cleanup ... ok
[INFO] [stdout] test test_while_with_return ... ok
[INFO] [stdout] test test_for_empty_body ... ok
[INFO] [stdout] test test_early_return_nested ... ok
[INFO] [stdout] test test_while_empty_body_never_runs ... ok
[INFO] [stdout] test test_continue_with_struct_cleanup ... ok
[INFO] [stdout] test test_for_only_assignment ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/loop_stress.rs (/opt/rustwide/target/debug/deps/loop_stress-d38250c4adcc22c5)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test test_fibonacci_while ... ok
[INFO] [stdout] test test_factorial_for ... ok
[INFO] [stdout] test test_large_while_1000 ... ok
[INFO] [stdout] test test_large_for_inclusive_1000 ... ok
[INFO] [stdout] test test_mixed_for_in_while ... ok
[INFO] [stdout] test test_for_continue_inner ... ok
[INFO] [stdout] test test_large_for_1000 ... ok
[INFO] [stdout] test test_loop_multiple_break_conditions ... ok
[INFO] [stdout] test test_for_break_inner ... ok
[INFO] [stdout] test test_determinism ... ok
[INFO] [stdout] test test_compound_ops_in_loop ... ok
[INFO] [stdout] test test_edge_loop_immediate_break ... ok
[INFO] [stdout] test test_edge_for_single_iteration ... ok
[INFO] [stdout] test test_nested_for_3_levels ... ok
[INFO] [stdout] test test_edge_while_never_runs ... ok
[INFO] [stdout] test test_edge_for_empty_range ... ok
[INFO] [stdout] test test_nested_return_when_found ... ok
[INFO] [stdout] test test_while_continue_inner ... ok
[INFO] [stdout] test test_shift_in_loop ... ok
[INFO] [stdout] test test_nested_while_3_levels ... ok
[INFO] [stdout] test test_mixed_while_in_for ... ok
[INFO] [stdout] test test_loop_with_zero_iterations ... ok
[INFO] [stdout] test test_xor_in_loop ... ok
[INFO] [stdout] test test_while_break_inner ... ok
[INFO] [stdout] test test_while_mixed_continue_break ... ok
[INFO] [stdout] test test_protection_level_equivalence_while ... ok
[INFO] [stderr]      Running tests/loops.rs (/opt/rustwide/target/debug/deps/loops-95e996fef3661754)
[INFO] [stdout] test test_protection_level_equivalence_for ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 49.54s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_compound_assignments ... ok
[INFO] [stdout] test test_for_skip_mult_3 ... ok
[INFO] [stdout] test test_for_sum_inclusive ... ok
[INFO] [stdout] test test_for_sum_range ... ok
[INFO] [stdout] test test_for_nested ... ok
[INFO] [stdout] test test_loop_with_break ... ok
[INFO] [stdout] test test_loop_find_power_of_2 ... ok
[INFO] [stdout] test test_while_skip_evens ... ok
[INFO] [stdout] test test_while_find_first_div_3 ... ok
[INFO] [stdout] test test_while_nested ... ok
[INFO] [stdout] test test_while_countdown ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/match_ops.rs (/opt/rustwide/target/debug/deps/match_ops-812ff689fafad353)
[INFO] [stdout] 
[INFO] [stdout] running 45 tests
[INFO] [stdout] test test_match_array_like_pattern ... ok
[INFO] [stdout] test test_match_as_expression ... ok
[INFO] [stdout] test test_match_at_binding_simple ... ok
[INFO] [stdout] test test_match_at_binding_or ... ok
[INFO] [stdout] test test_match_complex_compute ... ok
[INFO] [stdout] test test_match_fibonacci ... ok
[INFO] [stdout] test test_match_factorial ... ok
[INFO] [stdout] test test_match_at_binding_range ... ok
[INFO] [stdout] test test_match_grade_ranges ... ok
[INFO] [stdout] test test_match_first_last ... ok
[INFO] [stdout] test test_match_guard ... ok
[INFO] [stdout] test test_match_guard_computation ... ok
[INFO] [stdout] test test_match_in_expression ... ok
[INFO] [stdout] test test_match_integer_literal ... ok
[INFO] [stdout] test test_match_in_loop ... ok
[INFO] [stdout] test test_match_nested_tuple ... ok
[INFO] [stdout] test test_match_outer_variable ... ok
[INFO] [stdout] test test_match_or_pattern ... ok
[INFO] [stdout] test test_match_state_machine ... ok
[INFO] [stdout] test test_match_struct_destructure ... ok
[INFO] [stdout] test test_match_struct_rename ... ok
[INFO] [stdout] test test_match_struct_literal_field ... ok
[INFO] [stdout] test test_match_tagged_union_result ... ok
[INFO] [stdout] test test_match_tuple_destructure ... ok
[INFO] [stdout] test test_match_tuple_struct ... ok
[INFO] [stdout] test test_match_tuple_struct_destructure ... ok
[INFO] [stdout] test test_match_state_tagged ... ok
[INFO] [stdout] test test_match_struct_multiple_patterns ... ok
[INFO] [stdout] test test_match_tagged_union_option ... ok
[INFO] [stdout] test test_match_tuple_struct_literals ... ok
[INFO] [stdout] test test_match_tuple_struct_three_fields ... ok
[INFO] [stdout] test test_match_range_inclusive ... ok
[INFO] [stdout] test test_match_tuple_struct_wildcard ... ok
[INFO] [stdout] test test_match_tuple_binding ... ok
[INFO] [stdout] test test_match_underscore_binding ... ok
[INFO] [stdout] test test_match_tuple_with_literals ... ok
[INFO] [stdout] test test_match_wildcard ... ok
[INFO] [stdout] test test_match_weekend ... ok
[INFO] [stdout] test test_match_variable_binding ... ok
[INFO] [stdout] test test_match_tuple_struct_multi_arm ... ok
[INFO] [stdout] test test_match_struct_wildcard ... ok
[INFO] [stdout] test test_nested_match ... ok
[INFO] [stdout] test test_match_with_computation ... ok
[INFO] [stdout] test test_match_standard ... ok
[INFO] [stdout] test test_match_paranoid ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 45 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.92s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/multi_size.rs (/opt/rustwide/target/debug/deps/multi_size-873f961d90438766)
[INFO] [stdout] 
[INFO] [stdout] running 26 tests
[INFO] [stdout] test test_load32 ... ok
[INFO] [stdout] test test_load64 ... ok
[INFO] [stdout] test test_load16 ... ok
[INFO] [stdout] test test_push_imm32 ... ok
[INFO] [stdout] test test_load8 ... ok
[INFO] [stdout] test test_push_imm16 ... ok
[INFO] [stdout] test test_push_imm16_max ... ok
[INFO] [stdout] test test_sext16_negative ... ok
[INFO] [stdout] test test_push_imm32_max ... ok
[INFO] [stdout] test test_load_different_sizes_same_data ... ok
[INFO] [stdout] test test_sext16_positive ... ok
[INFO] [stdout] test test_sext8_minus_one ... ok
[INFO] [stdout] test test_sext8_negative ... ok
[INFO] [stdout] test test_store16 ... ok
[INFO] [stdout] test test_store32 ... ok
[INFO] [stdout] test test_store8 ... ok
[INFO] [stdout] test test_trunc8 ... ok
[INFO] [stdout] test test_sext32_negative ... ok
[INFO] [stdout] test test_sext32_positive ... ok
[INFO] [stdout] test test_sext_trunc_roundtrip ... ok
[INFO] [stdout] test test_chained_conversions ... ok
[INFO] [stdout] test test_store_at_offset ... ok
[INFO] [stdout] test test_trunc16 ... ok
[INFO] [stdout] test test_trunc32 ... ok
[INFO] [stdout] test test_store64 ... ok
[INFO] [stdout] test test_sext8_positive ... ok
[INFO] [stderr]      Running tests/mutation_tests.rs (/opt/rustwide/target/debug/deps/mutation_tests-49b8a56e12b7936b)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[INFO] [stdout] test test_mutated_add_large ... ok
[INFO] [stdout] test test_mutated_add_basic ... ok
[INFO] [stdout] test test_mutated_add_overflow ... ok
[INFO] [stdout] test test_mutated_add_zero ... ok
[INFO] [stdout] test test_mutated_and_zero ... ok
[INFO] [stdout] test test_mutated_bitwise_comprehensive ... ok
[INFO] [stdout] test test_mutated_dec_one ... ok
[INFO] [stdout] test test_mutated_dec_underflow ... ok
[INFO] [stdout] test test_mutated_dec_basic ... ok
[INFO] [stdout] test test_mutated_inc_basic ... ok
[INFO] [stdout] test test_mutated_inc_dec_identity ... ok
[INFO] [stdout] test test_mutated_inc_overflow ... ok
[INFO] [stdout] test test_mutated_mul_basic ... ok
[INFO] [stdout] test test_mutated_mul_one ... ok
[INFO] [stdout] test test_mutated_mul_overflow ... ok
[INFO] [stdout] test test_mutated_not_max ... ok
[INFO] [stdout] test test_mutated_not_zero ... ok
[INFO] [stdout] test test_mutated_or_basic ... ok
[INFO] [stdout] test test_mutated_handlers_comprehensive ... ok
[INFO] [stdout] test test_mutated_sub_zero ... ok
[INFO] [stdout] test test_mutated_xor_and_or_demorgan ... ok
[INFO] [stdout] test test_mutated_xor_basic ... ok
[INFO] [stdout] test test_mutated_xor_max ... ok
[INFO] [stdout] test test_mutated_xor_same ... ok
[INFO] [stdout] test test_mutated_xor_zero ... ok
[INFO] [stdout] test test_mutated_add_sub_identity ... ok
[INFO] [stdout] test test_mutated_and_basic ... ok
[INFO] [stdout] test test_mutated_not_double ... ok
[INFO] [stdout] test test_mutated_sub_large ... ok
[INFO] [stdout] test test_mutated_or_max ... ok
[INFO] [stdout] test test_mutated_or_zero ... ok
[INFO] [stdout] test test_mutated_and_max ... ok
[INFO] [stdout] test test_mutated_not_basic ... ok
[INFO] [stdout] test test_mutated_sub_underflow ... ok
[INFO] [stdout] test test_mutated_sub_basic ... ok
[INFO] [stdout] test test_mutated_inc_zero ... ok
[INFO] [stdout] test test_mutated_mul_add_distribution ... ok
[INFO] [stdout] test test_mutated_mul_zero ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/native_functions.rs (/opt/rustwide/target/debug/deps/native_functions-bc9bbe7d5c6b94e5)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test test_hash_function ... ok
[INFO] [stdout] test test_conditional_jump_with_natives ... ok
[INFO] [stdout] test test_anticheat_simulation ... ok
[INFO] [stdout] test test_multiple_native_calls ... ok
[INFO] [stdout] test test_conditional_jump_false_branch ... ok
[INFO] [stdout] test test_native_call_chain ... ok
[INFO] [stdout] test test_native_call_one_arg ... ok
[INFO] [stdout] test test_native_function_not_found ... ok
[INFO] [stdout] test test_native_result_used_in_computation ... ok
[INFO] [stdout] test test_native_call_many_args ... ok
[INFO] [stdout] test test_native_call_no_args ... ok
[INFO] [stdout] test test_native_with_closure_state ... ok
[INFO] [stdout] test test_native_call_two_args ... ok
[INFO] [stdout] test test_registry_builder ... ok
[INFO] [stdout] test test_native_with_vm_arithmetic ... ok
[INFO] [stderr]      Running tests/native_registry.rs (/opt/rustwide/target/debug/deps/native_registry-fb50c548a9a4eae8)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test test_builder ... ok
[INFO] [stdout] test test_call_with_args ... ok
[INFO] [stdout] test test_not_found ... ok
[INFO] [stdout] test test_already_registered ... ok
[INFO] [stdout] test test_count_and_clear ... ok
[INFO] [stdout] test test_register_and_call ... ok
[INFO] [stdout] test test_replace ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/polymorphic.rs (/opt/rustwide/target/debug/deps/polymorphic-33d463345fb3c470)
[INFO] [stdout] 
[INFO] [stdout] running 37 tests
[INFO] [stdout] test test_debug_complex ... ok
[INFO] [stdout] test test_debug_mul ... ok
[INFO] [stdout] test test_debug_add ... ok
[INFO] [stdout] test test_cross_level_add ... ok
[INFO] [stdout] test test_paranoid_xor ... ok
[INFO] [stdout] test test_paranoid_complex ... ok
[INFO] [stdout] test test_edge_powers_of_two ... ok
[INFO] [stdout] test test_paranoid_add ... ok
[INFO] [stdout] test test_cross_level_complex ... ok
[INFO] [stdout] test test_poly_bitwise_and ... ok
[INFO] [stdout] test test_determinism_single_function ... ok
[INFO] [stdout] test test_cross_level_mul ... ok
[INFO] [stdout] test test_paranoid_mul ... ok
[INFO] [stdout] test test_determinism_across_variants has been running for over 60 seconds
[INFO] [stdout] test test_edge_max has been running for over 60 seconds
[INFO] [stdout] test test_edge_overflow has been running for over 60 seconds
[INFO] [stdout] test test_edge_zero has been running for over 60 seconds
[INFO] [stdout] test test_hash_variants has been running for over 60 seconds
[INFO] [stdout] test test_poly_add_equivalence has been running for over 60 seconds
[INFO] [stdout] test test_edge_max ... ok
[INFO] [stdout] test test_poly_bitwise_xor ... ok
[INFO] [stdout] test test_poly_bitwise_or ... ok
[INFO] [stdout] test test_poly_const_only ... ok
[INFO] [stdout] test test_poly_complex_equivalence ... ok
[INFO] [stdout] test test_edge_overflow ... ok
[INFO] [stdout] test test_edge_zero ... ok
[INFO] [stdout] test test_stress_random_inputs ... ok
[INFO] [stdout] test test_stress_shifts ... ok
[INFO] [stdout] test test_poly_shl ... ok
[INFO] [stdout] test test_poly_const_expr has been running for over 60 seconds
[INFO] [stdout] test test_poly_not ... ok
[INFO] [stdout] test test_poly_large_add has been running for over 60 seconds
[INFO] [stdout] test test_poly_single_double ... ok
[INFO] [stdout] test test_poly_const_expr ... ok
[INFO] [stdout] test test_poly_large_add ... ok
[INFO] [stdout] test test_poly_large_const has been running for over 60 seconds
[INFO] [stdout] test test_poly_single_dec ... ok
[INFO] [stdout] test test_poly_nested ... ok
[INFO] [stdout] test test_poly_large_const ... ok
[INFO] [stdout] test test_poly_nested_paranoid ... ok
[INFO] [stdout] test test_stress_nested ... ok
[INFO] [stdout] test test_poly_shr ... ok
[INFO] [stdout] test test_poly_single_inc ... ok
[INFO] [stdout] test test_poly_add_equivalence ... ok
[INFO] [stdout] test test_determinism_across_variants ... ok
[INFO] [stdout] test test_hash_variants ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 133.64s
[INFO] [stderr]      Running tests/smc_tests.rs (/opt/rustwide/target/debug/deps/smc_tests-f8ee83e9bb5e3f99)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test test_different_seeds_different_encryption ... ok
[INFO] [stdout] test test_smc_after_execution_re_encrypted ... ok
[INFO] [stdout] test test_smc_bitwise_operations ... ok
[INFO] [stdout] test test_encrypt_decrypt_roundtrip ... ok
[INFO] [stdout] test test_smc_conditional_jump_not_taken ... ok
[INFO] [stdout] test test_smc_conditional_jump_taken ... ok
[INFO] [stdout] test test_smc_empty_input ... ok
[INFO] [stdout] test test_smc_large_immediate ... ok
[INFO] [stdout] test test_smc_multiple_operations ... ok
[INFO] [stdout] test test_smc_fibonacci_10 ... ok
[INFO] [stdout] test test_smc_matches_normal_execution ... ok
[INFO] [stdout] test test_smc_simple_addition ... ok
[INFO] [stdout] test test_smc_simple_loop ... ok
[INFO] [stdout] test test_smc_nested_loop ... ok
[INFO] [stdout] test test_smc_swap_operation ... ok
[INFO] [stdout] test test_smc_stack_operations ... ok
[INFO] [stdout] test test_smc_window_size_larger ... ok
[INFO] [stderr]      Running tests/stress_test.rs (/opt/rustwide/target/debug/deps/stress_test-a030ae7f150b778e)
[INFO] [stdout] test test_smc_window_size_1 ... ok
[INFO] [stdout] test test_smc_unconditional_jump ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test stress_test_constant_only ... ok
[INFO] [stdout] test stress_test_bitwise_ops ... ok
[INFO] [stdout] test stress_test_shift_ops ... ok
[INFO] [stdout] test stress_test_single_arg ... ok
[INFO] [stdout] test stress_test_edge_cases ... ok
[INFO] [stdout] test stress_test_xor_random_inputs ... ok
[INFO] [stdout] test stress_test_complex_random_inputs ... ok
[INFO] [stdout] test stress_test_determinism ... ok
[INFO] [stdout] test stress_test_mul_random_inputs ... ok
[INFO] [stdout] test stress_test_all_levels_equivalent ... ok
[INFO] [stdout] test stress_test_add_random_inputs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 20.84s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/string_obfuscation.rs (/opt/rustwide/target/debug/deps/string_obfuscation-9bb6a7e7c3472712)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test test_aegis_str_empty ... ok
[INFO] [stdout] test test_aegis_str_with_escapes ... ok
[INFO] [stdout] test test_aegis_str_in_match ... ok
[INFO] [stdout] test test_aegis_str_in_format ... ok
[INFO] [stdout] test test_aegis_str_unicode ... ok
[INFO] [stdout] test test_concat_strings ... ok
[INFO] [stdout] test test_empty_string_handling ... ok
[INFO] [stdout] test test_aegis_str_basic ... ok
[INFO] [stdout] test test_aegis_str_long_string ... ok
[INFO] [stdout] test test_error_messages ... ok
[INFO] [stdout] test test_special_chars ... ok
[INFO] [stdout] test test_unicode_strings ... ok
[INFO] [stdout] test test_repeated_calls_return_same_value ... ok
[INFO] [stdout] test test_multiline_string ... ok
[INFO] [stdout] test test_secret_key ... ok
[INFO] [stdout] test test_aegis_str_deterministic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/string_ops.rs (/opt/rustwide/target/debug/deps/string_ops-2c41ece735a928b8)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test test_empty_string ... ok
[INFO] [stdout] test test_str_bounds_check ... ok
[INFO] [stdout] test test_str_cmp_equal ... ok
[INFO] [stdout] test test_str_cmp_greater ... ok
[INFO] [stdout] test test_str_hash ... ok
[INFO] [stdout] test test_str_new_and_len ... ok
[INFO] [stdout] test test_str_hash_consistency ... ok
[INFO] [stdout] test test_str_cmp_less ... ok
[INFO] [stdout] test test_str_push ... ok
[INFO] [stdout] test test_str_get ... ok
[INFO] [stdout] test test_str_set ... ok
[INFO] [stdout] test test_str_eq_different_lengths ... ok
[INFO] [stdout] test test_str_eq_equal ... ok
[INFO] [stdout] test test_str_concat_content ... ok
[INFO] [stdout] test test_str_eq_not_equal ... ok
[INFO] [stdout] test test_str_concat ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/struct_ops.rs (/opt/rustwide/target/debug/deps/struct_ops-5bc86340f56397d2)
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test test_field_init_mixed ... ok
[INFO] [stdout] test test_field_init_shorthand ... ok
[INFO] [stdout] test test_multiple_tuple_structs ... ok
[INFO] [stdout] test test_multiple_structs ... ok
[INFO] [stdout] test test_numeric_field_init ... ok
[INFO] [stdout] test test_struct_basic ... ok
[INFO] [stdout] test test_struct_bitwise ... ok
[INFO] [stdout] test test_functional_update_computed ... ok
[INFO] [stdout] test test_struct_accumulator_pattern ... ok
[INFO] [stdout] test test_functional_update_basic ... ok
[INFO] [stdout] test test_functional_update_multiple ... ok
[INFO] [stdout] test test_struct_comparison_chain ... ok
[INFO] [stdout] test test_functional_update_copy_all ... ok
[INFO] [stdout] test test_struct_complex_init ... ok
[INFO] [stdout] test test_struct_complex_arithmetic ... ok
[INFO] [stdout] test test_struct_conditional_update ... ok
[INFO] [stdout] test test_struct_field_assignment ... ok
[INFO] [stdout] test test_struct_fibonacci ... ok
[INFO] [stdout] test test_struct_field_swap ... ok
[INFO] [stdout] test test_struct_field_as_index ... ok
[INFO] [stdout] test test_struct_in_condition ... ok
[INFO] [stdout] test test_struct_in_loop ... ok
[INFO] [stdout] test test_struct_loop_break ... ok
[INFO] [stdout] test test_struct_multiple_assignments ... ok
[INFO] [stdout] test test_struct_loop_continue ... ok
[INFO] [stdout] test test_struct_many_fields ... ok
[INFO] [stdout] test test_struct_multiple_instances ... ok
[INFO] [stdout] test test_struct_nested_loops ... ok
[INFO] [stdout] test test_struct_three_fields ... ok
[INFO] [stdout] test test_tuple_struct ... ok
[INFO] [stdout] test test_struct_while_loop ... ok
[INFO] [stdout] test test_struct_field_from_struct ... ok
[INFO] [stdout] test test_struct_in_expression ... ok
[INFO] [stdout] test test_struct_field_reuse ... ok
[INFO] [stdout] test test_tuple_struct_expr ... ok
[INFO] [stdout] test test_tuple_struct_mutation ... ok
[INFO] [stdout] test test_tuple_struct_triple ... ok
[INFO] [stdout] test test_unit_struct ... ok
[INFO] [stdout] test test_unit_struct_with_braces ... ok
[INFO] [stdout] test test_struct_standard ... ok
[INFO] [stdout] test test_struct_paranoid ... ok
[INFO] [stdout] test test_struct_all_levels_equivalent ... ok
[INFO] [stderr]      Running tests/substitution_test.rs (/opt/rustwide/target/debug/deps/substitution_test-c70d8d0839d607bd)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.26s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test tests::test_add_chain ... ok
[INFO] [stdout] test tests::test_add_powers_of_two ... ok
[INFO] [stdout] test tests::test_add_then_sub ... ok
[INFO] [stdout] test tests::test_add_identity ... ok
[INFO] [stdout] test tests::test_add_baseline ... ok
[INFO] [stdout] test tests::test_add_with_not ... ok
[INFO] [stdout] test tests::test_add_wrapping ... ok
[INFO] [stdout] test tests::test_add_zero ... ok
[INFO] [stdout] test tests::test_complex_expr ... ok
[INFO] [stdout] test tests::test_alternating ... ok
[INFO] [stdout] test tests::test_add_with_xor ... ok
[INFO] [stdout] test tests::test_sub_baseline ... ok
[INFO] [stdout] test tests::test_sub_then_add ... ok
[INFO] [stdout] test tests::test_sub_wrapping ... ok
[INFO] [stdout] test tests::test_sub_chain ... ok
[INFO] [stdout] test tests::test_sub_self ... ok
[INFO] [stdout] test tests::test_add_max ... ok
[INFO] [stdout] test tests::test_sub_zero ... ok
[INFO] [stdout] test tests::test_complex_paranoid ... ok
[INFO] [stdout] test tests::test_sub_standard ... ok
[INFO] [stdout] test tests::test_sub_paranoid ... ok
[INFO] [stdout] test tests::test_add_standard ... ok
[INFO] [stdout] test tests::test_add_paranoid ... ok
[INFO] [stdout] test tests::test_complex_standard ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.57s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tuple_ops.rs (/opt/rustwide/target/debug/deps/tuple_ops-25cca5530325eae1)
[INFO] [stdout] 
[INFO] [stdout] running 50 tests
[INFO] [stdout] test test_nested_tuple_basic ... ok
[INFO] [stdout] test test_nested_tuple_compute ... ok
[INFO] [stdout] test test_nested_tuple_deep ... ok
[INFO] [stdout] test test_nested_tuple_inner_access ... ok
[INFO] [stdout] test test_single_element_tuple ... ok
[INFO] [stdout] test test_three_element_tuple ... ok
[INFO] [stdout] test test_tuple_chained_computation ... ok
[INFO] [stdout] test test_tuple_comparison_chain ... ok
[INFO] [stdout] test test_tuple_complex_arithmetic ... ok
[INFO] [stdout] test test_tuple_element_reuse ... ok
[INFO] [stdout] test test_tuple_fibonacci ... ok
[INFO] [stdout] test test_tuple_from_tuple ... ok
[INFO] [stdout] test test_tuple_in_condition ... ok
[INFO] [stdout] test test_tuple_index_2 ... ok
[INFO] [stdout] test test_tuple_index_in_expr ... ok
[INFO] [stdout] test test_tuple_in_loop ... ok
[INFO] [stdout] test test_tuple_loop_break ... ok
[INFO] [stdout] test test_tuple_multiple_assignments ... ok
[INFO] [stdout] test test_tuple_multiple_instances ... ok
[INFO] [stdout] test test_tuple_nested_loops ... ok
[INFO] [stdout] test test_tuple_deep_extraction ... ok
[INFO] [stdout] test test_tuple_element_assignment ... ok
[INFO] [stdout] test test_tuple_bitwise ... ok
[INFO] [stdout] test test_tuple_power ... ok
[INFO] [stdout] test test_tuple_struct_basic ... ok
[INFO] [stdout] test test_tuple_gcd ... ok
[INFO] [stdout] test test_tuple_conditional_update ... ok
[INFO] [stdout] test test_tuple_multiple_nested ... ok
[INFO] [stdout] test test_tuple_index_1 ... ok
[INFO] [stdout] test test_tuple_struct_mutation ... ok
[INFO] [stdout] test test_tuple_loop_continue ... ok
[INFO] [stdout] test test_tuple_index_0 ... ok
[INFO] [stdout] test test_tuple_many_elements ... ok
[INFO] [stdout] test test_tuple_struct_complex ... ok
[INFO] [stdout] test test_tuple_struct_field_access ... ok
[INFO] [stdout] test test_tuple_struct_three_fields ... ok
[INFO] [stdout] test test_tuple_structs_interaction ... ok
[INFO] [stdout] test test_tuple_type_tracking ... ok
[INFO] [stdout] test test_tuple_collatz_steps ... ok
[INFO] [stdout] test test_unit_tuple ... ok
[INFO] [stdout] test test_tuple_swap ... ok
[INFO] [stdout] test test_tuple_variable_elements ... ok
[INFO] [stdout] test test_tuple_with_expressions ... ok
[INFO] [stdout] test test_two_element_tuple ... ok
[INFO] [stdout] test test_tuple_while_loop ... ok
[INFO] [stdout] test test_tuple_factorial ... ok
[INFO] [stdout] test test_tuple_accumulator ... ok
[INFO] [stdout] test test_tuple_standard ... ok
[INFO] [stdout] test test_tuple_paranoid ... ok
[INFO] [stdout] test test_tuple_protection_levels ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 8.64s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/value_cryptor.rs (/opt/rustwide/target/debug/deps/value_cryptor-40bb77f9d59143ec)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test test_multiple_constants ... ok
[INFO] [stdout] test test_many_constants_stress ... ok
[INFO] [stdout] test test_large_constant ... ok
[INFO] [stdout] test test_one_constant ... ok
[INFO] [stdout] test test_power_of_two ... ok
[INFO] [stdout] test test_secret_key_check ... ok
[INFO] [stdout] test test_zero_constant ... ok
[INFO] [stdout] test test_nested_constants ... ok
[INFO] [stdout] test test_max_constant ... ok
[INFO] [stdout] test test_medium_constant ... ok
[INFO] [stdout] test test_small_constant ... ok
[INFO] [stdout] test test_constant_in_condition ... ok
[INFO] [stdout] test test_very_large_constant ... ok
[INFO] [stdout] test test_all_levels_same_result ... ok
[INFO] [stdout] test test_bit_operations ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 25.77s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/vector_ops.rs (/opt/rustwide/target/debug/deps/vector_ops-8154388ae88b704d)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test test_vec_capacity ... ok
[INFO] [stdout] test test_large_repeat_array ... ok
[INFO] [stdout] test test_vec_bounds_check ... ok
[INFO] [stdout] test test_vec_get ... ok
[INFO] [stdout] test test_vec_elem_size_2 ... ok
[INFO] [stdout] test test_vec_new_and_len ... ok
[INFO] [stdout] test test_vec_clear ... ok
[INFO] [stdout] test test_list_form_array ... ok
[INFO] [stdout] test test_vec_push ... ok
[INFO] [stdout] test test_vec_repeat ... ok
[INFO] [stdout] test test_vec_pop ... ok
[INFO] [stdout] test test_vec_set ... ok
[INFO] [stdout] test test_vec_elem_size_4 ... ok
[INFO] [stdout] test test_vec_elem_size_1 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/vm_opcodes.rs (/opt/rustwide/target/debug/deps/vm_opcodes-e4c5049615c4161c)
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test test_backward_jump ... ok
[INFO] [stdout] test test_and_operation ... ok
[INFO] [stdout] test test_cmp_equal ... ok
[INFO] [stdout] test test_bitwise_ops ... ok
[INFO] [stdout] test test_call_ret ... ok
[INFO] [stdout] test test_complex_arithmetic ... ok
[INFO] [stdout] test test_cmp_not_equal ... ok
[INFO] [stdout] test test_conditional_jump ... ok
[INFO] [stdout] test test_dec ... ok
[INFO] [stdout] test test_dup_swap ... ok
[INFO] [stdout] test test_extended_registers ... ok
[INFO] [stdout] test test_halt_with_empty_stack ... ok
[INFO] [stdout] test test_fibonacci_like ... ok
[INFO] [stdout] test test_inc_overflow ... ok
[INFO] [stdout] test test_input_len ... ok
[INFO] [stdout] test test_input_read ... ok
[INFO] [stdout] test test_invalid_jump_target ... ok
[INFO] [stdout] test test_jgt_greater ... ok
[INFO] [stdout] test test_jge_equal ... ok
[INFO] [stdout] test test_jlt_less ... ok
[INFO] [stdout] test test_drop ... ok
[INFO] [stdout] test test_loop_sum ... ok
[INFO] [stdout] test test_memory_out_of_bounds ... ok
[INFO] [stdout] test test_mov_reg ... ok
[INFO] [stdout] test test_native_write ... ok
[INFO] [stdout] test test_multiply ... ok
[INFO] [stdout] test test_nop_n ... ok
[INFO] [stdout] test test_push_imm_64bit ... ok
[INFO] [stdout] test test_nop ... ok
[INFO] [stdout] test test_or_operation ... ok
[INFO] [stdout] test test_not ... ok
[INFO] [stdout] test test_multiple_registers ... ok
[INFO] [stdout] test test_read_multiple_input_values ... ok
[INFO] [stdout] test test_not_pattern ... ok
[INFO] [stdout] test test_nested_calls ... ok
[INFO] [stdout] test test_opaque_false ... ok
[INFO] [stdout] test test_ret_from_main ... ok
[INFO] [stdout] test test_jle_less ... ok
[INFO] [stdout] test test_inc ... ok
[INFO] [stdout] test test_rotate_left ... ok
[INFO] [stdout] test test_opaque_true ... ok
[INFO] [stdout] test test_push_pop ... ok
[INFO] [stdout] test test_rotate_right ... ok
[INFO] [stdout] test test_shift_left ... ok
[INFO] [stdout] test test_shift_right ... ok
[INFO] [stdout] test test_stack_underflow ... ok
[INFO] [stdout] test test_subtraction ... ok
[INFO] [stdout] test test_swap ... ok
[INFO] [stdout] test test_wrapping_arithmetic ... ok
[INFO] [stdout] test test_xor ... ok
[INFO] [stdout] test test_zero_flag_not_set ... ok
[INFO] [stdout] test test_zero_flag_set ... ok
[INFO] [stdout] test test_invalid_opcode ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_invalid_opcode stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_invalid_opcode' (1984) panicked at tests/vm_opcodes.rs:677:5:
[INFO] [stdout] Random opcode byte should cause an error
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x64c568e18e32 - std[716c9a7a72e5c14e]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x64c568e18e32 - std[716c9a7a72e5c14e]::backtrace_rs::backtrace::trace_unsynchronized::<std[716c9a7a72e5c14e]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x64c568e18e32 - std[716c9a7a72e5c14e]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x64c568e18e32 - <<std[716c9a7a72e5c14e]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[c5ed12ab89cc536a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x64c568e2d6ca - <core[c5ed12ab89cc536a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x64c568e2d6ca - core[c5ed12ab89cc536a]::fmt::write
[INFO] [stdout]    6:     0x64c568e1dc66 - std[716c9a7a72e5c14e]::io::default_write_fmt::<alloc[9c68fdf4f4f29218]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x64c568e1dc66 - <alloc[9c68fdf4f4f29218]::vec::Vec<u8> as std[716c9a7a72e5c14e]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x64c568df72ff - <std[716c9a7a72e5c14e]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x64c568df72ff - std[716c9a7a72e5c14e]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x64c568e11119 - std[716c9a7a72e5c14e]::panicking::default_hook
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x64c568d9850e - <alloc[9c68fdf4f4f29218]::boxed::Box<dyn for<'a, 'b> core[c5ed12ab89cc536a]::ops::function::Fn<(&'a std[716c9a7a72e5c14e]::panic::PanicHookInfo<'b>,), Output = ()> + core[c5ed12ab89cc536a]::marker::Sync + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::Fn<(&std[716c9a7a72e5c14e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x64c568d9850e - test[64760d2bdea328cc]::test_main_with_exit_callback::<test[64760d2bdea328cc]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x64c568e112d2 - <alloc[9c68fdf4f4f29218]::boxed::Box<dyn for<'a, 'b> core[c5ed12ab89cc536a]::ops::function::Fn<(&'a std[716c9a7a72e5c14e]::panic::PanicHookInfo<'b>,), Output = ()> + core[c5ed12ab89cc536a]::marker::Sync + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::Fn<(&std[716c9a7a72e5c14e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x64c568e112d2 - std[716c9a7a72e5c14e]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x64c568df73ea - std[716c9a7a72e5c14e]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x64c568deed89 - std[716c9a7a72e5c14e]::sys::backtrace::__rust_end_short_backtrace::<std[716c9a7a72e5c14e]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x64c568df820d - __rustc[4f0b026143eab78e]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x64c568e2dd5c - core[c5ed12ab89cc536a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x64c568d89b28 - vm_opcodes[4a95135d2ea1282b]::test_invalid_opcode
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/vm_opcodes.rs:677:5
[INFO] [stdout]   20:     0x64c568d870b7 - vm_opcodes[4a95135d2ea1282b]::test_invalid_opcode::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/vm_opcodes.rs:670:25
[INFO] [stdout]   21:     0x64c568d8c326 - <vm_opcodes[4a95135d2ea1282b]::test_invalid_opcode::{closure#0} as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x64c568d8c8bb - <fn() -> core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x64c568d8c8bb - test[64760d2bdea328cc]::__rust_begin_short_backtrace::<core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>, fn() -> core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:663:18
[INFO] [stdout]   24:     0x64c568d9911a - test[64760d2bdea328cc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:686:74
[INFO] [stdout]   25:     0x64c568d9911a - <core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<test[64760d2bdea328cc]::run_test_in_process::{closure#0}> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   26:     0x64c568d9911a - std[716c9a7a72e5c14e]::panicking::catch_unwind::do_call::<core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<test[64760d2bdea328cc]::run_test_in_process::{closure#0}>, core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x64c568d9911a - std[716c9a7a72e5c14e]::panicking::catch_unwind::<core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>, core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<test[64760d2bdea328cc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x64c568d9911a - std[716c9a7a72e5c14e]::panic::catch_unwind::<core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<test[64760d2bdea328cc]::run_test_in_process::{closure#0}>, core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x64c568d9911a - test[64760d2bdea328cc]::run_test_in_process
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:686:27
[INFO] [stdout]   30:     0x64c568d9911a - test[64760d2bdea328cc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:607:43
[INFO] [stdout]   31:     0x64c568d935e4 - test[64760d2bdea328cc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:637:41
[INFO] [stdout]   32:     0x64c568d935e4 - std[716c9a7a72e5c14e]::sys::backtrace::__rust_begin_short_backtrace::<test[64760d2bdea328cc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x64c568d9bc12 - std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked::<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   34:     0x64c568d9bc12 - <core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   35:     0x64c568d9bc12 - std[716c9a7a72e5c14e]::panicking::catch_unwind::do_call::<core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x64c568d9bc12 - std[716c9a7a72e5c14e]::panicking::catch_unwind::<(), core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x64c568d9bc12 - std[716c9a7a72e5c14e]::panic::catch_unwind::<core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe<std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x64c568d9bc12 - std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked::<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   39:     0x64c568d9bc12 - <std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked<test[64760d2bdea328cc]::run_test::{closure#1}, ()>::{closure#1} as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x64c568e186df - <alloc[9c68fdf4f4f29218]::boxed::Box<dyn core[c5ed12ab89cc536a]::ops::function::FnOnce<(), Output = ()> + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   41:     0x64c568e186df - <std[716c9a7a72e5c14e]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/thread/unix.rs:119:17
[INFO] [stdout]   42:     0x7eee9f0e4aa4 - <unknown>
[INFO] [stdout]   43:     0x7eee9f171a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_invalid_opcode
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 52 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test vm_opcodes`
[INFO] running `Command { std: "docker" "inspect" "fe3c593ff0cb8fe6be6c63f8a0c98475905d643aa3c13b7c619cc355dfc8cc7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fe3c593ff0cb8fe6be6c63f8a0c98475905d643aa3c13b7c619cc355dfc8cc7d", kill_on_drop: false }`
[INFO] [stdout] fe3c593ff0cb8fe6be6c63f8a0c98475905d643aa3c13b7c619cc355dfc8cc7d