[INFO] cloning repository https://github.com/keiv-fly/202507_Kayton
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/keiv-fly/202507_Kayton" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkeiv-fly%2F202507_Kayton", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkeiv-fly%2F202507_Kayton'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 9ada69fa60164f0fbf03f45c9751dc1e4c591102
[INFO] testing keiv-fly/202507_Kayton against master#d98a5da813da67eb189387b8ccfb73cf481275d8+rustflags=-Copt-level=3 for pr-138759
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkeiv-fly%2F202507_Kayton" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/keiv-fly/202507_Kayton
[INFO] finished tweaking git repo https://github.com/keiv-fly/202507_Kayton
[INFO] tweaked toml for git repo https://github.com/keiv-fly/202507_Kayton written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/keiv-fly/202507_Kayton on toolchain d98a5da813da67eb189387b8ccfb73cf481275d8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/keiv-fly/202507_Kayton 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" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d7e062f9d3a78947bd06e79238f822e1e6aba44f5e9a335082c970d64245e04b
[INFO] running `Command { std: "docker" "start" "-a" "d7e062f9d3a78947bd06e79238f822e1e6aba44f5e9a335082c970d64245e04b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d7e062f9d3a78947bd06e79238f822e1e6aba44f5e9a335082c970d64245e04b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d7e062f9d3a78947bd06e79238f822e1e6aba44f5e9a335082c970d64245e04b", kill_on_drop: false }`
[INFO] [stdout] d7e062f9d3a78947bd06e79238f822e1e6aba44f5e9a335082c970d64245e04b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 446f4b01b9288c584dbaa2f4bb114cb37ba1bad5f2925789f68120ad0c62fa43
[INFO] running `Command { std: "docker" "start" "-a" "446f4b01b9288c584dbaa2f4bb114cb37ba1bad5f2925789f68120ad0c62fa43", kill_on_drop: false }`
[INFO] [stderr]    Compiling windows_x86_64_gnu v0.53.0
[INFO] [stderr]    Compiling bumpalo v3.19.0
[INFO] [stderr]    Compiling windows-targets v0.53.3
[INFO] [stderr]    Compiling windows-sys v0.60.2
[INFO] [stderr]    Compiling kayton v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.56s
[INFO] running `Command { std: "docker" "inspect" "446f4b01b9288c584dbaa2f4bb114cb37ba1bad5f2925789f68120ad0c62fa43", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "446f4b01b9288c584dbaa2f4bb114cb37ba1bad5f2925789f68120ad0c62fa43", kill_on_drop: false }`
[INFO] [stdout] 446f4b01b9288c584dbaa2f4bb114cb37ba1bad5f2925789f68120ad0c62fa43
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2c2fb963ec030637000b58aca74877087d9286d1c78bc17d90191af48e36ac67
[INFO] running `Command { std: "docker" "start" "-a" "2c2fb963ec030637000b58aca74877087d9286d1c78bc17d90191af48e36ac67", kill_on_drop: false }`
[INFO] [stderr]    Compiling kayton v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.67s
[INFO] running `Command { std: "docker" "inspect" "2c2fb963ec030637000b58aca74877087d9286d1c78bc17d90191af48e36ac67", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2c2fb963ec030637000b58aca74877087d9286d1c78bc17d90191af48e36ac67", kill_on_drop: false }`
[INFO] [stdout] 2c2fb963ec030637000b58aca74877087d9286d1c78bc17d90191af48e36ac67
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid -Copt-level=3" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1bb897f44e20a4f10c9a40bf974fb4c3be3de1ba3bb82d682dd590fc6c3d2e1b
[INFO] running `Command { std: "docker" "start" "-a" "1bb897f44e20a4f10c9a40bf974fb4c3be3de1ba3bb82d682dd590fc6c3d2e1b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kayton-c18731c8a2f85398)
[INFO] [stdout] 
[INFO] [stdout] running 111 tests
[INFO] [stdout] test lexer::tests::program3_tokens ... ok
[INFO] [stdout] test codegen::tests::populates_global_vars ... ok
[INFO] [stdout] test parser::tests::program2_ast ... ok
[INFO] [stdout] test codegen::tests::program2_codegen ... ok
[INFO] [stdout] test lexer::tests::program2_tokens ... ok
[INFO] [stdout] test parser::tests::program1_ast ... ok
[INFO] [stdout] test parser::tests::program3_ast ... ok
[INFO] [stdout] test vm::tests::test_basic_f64_arithmetic ... ok
[INFO] [stdout] test vm::tests::test_backward_compatibility ... ok
[INFO] [stdout] test vm::tests::test_basic_i64_arithmetic ... ok
[INFO] [stdout] test vm::tests::test_backward_jump_bounds_check ... ok
[INFO] [stdout] test codegen::tests::program1_codegen ... ok
[INFO] [stdout] test lexer::tests::program1_tokens ... ok
[INFO] [stdout] test vm::tests::test_execution_with_no_timeout_specified ... ok
[INFO] [stdout] test vm::tests::test_f64_comparison ... ok
[INFO] [stdout] test vm::tests::test_complex_f64_operations ... ok
[INFO] [stdout] test vm::tests::test_execution_with_short_timeout ... ok
[INFO] [stdout] test vm::tests::test_f64_comparison_with_negatives ... ok
[INFO] [stdout] test vm::tests::test_invalid_jump_target ... ok
[INFO] [stdout] test vm::tests::test_invalid_opcode ... ok
[INFO] [stdout] test vm::tests::test_negative_numbers ... ok
[INFO] [stdout] test vm::tests::test_i64_comparison_ops ... ok
[INFO] [stdout] test vm::tests::test_register_base_offset ... ok
[INFO] [stdout] test vm::tests::test_register_raw_operations ... ok
[INFO] [stdout] test vm::tests::test_timeout_check_interval ... ok
[INFO] [stdout] test vm::tests::test_timeout_error_display ... ok
[INFO] [stdout] test vm::tests::test_execution_with_timeout_no_timeout ... ok
[INFO] [stdout] test vm::tests::test_unexpected_end_of_program ... ok
[INFO] [stdout] test vm::tests::test_type_conversions ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_builder_comparison_ops ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_builder_load_const_instructions ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_comparison_and_jumps_with_labels ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_comparison_old_vs_new_approach ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_conditional_jump_with_zero ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_fibonacci_with_labels ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_complex_control_flow ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_jump_backward_if_false_exit_loop ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_jump_backward_if_true_loop ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_jump_forward_if_true_false_condition ... ok
[INFO] [stdout] test vm::tests_call::test_call_host_multiple_calls ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_target_based_jumps ... ok
[INFO] [stdout] test vm::tests::test_f64_subtraction ... ok
[INFO] [stdout] test vm::tests_const_opcodes::test_load_const_value_and_slice ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_jump_forward_if_true_with_labels ... ok
[INFO] [stdout] test vm::tests_call::test_call_host_basic ... ok
[INFO] [stdout] test vm::tests_call::test_call_host_register_isolation ... ok
[INFO] [stdout] test vm::tests_const_pool::test_add_and_get_value_constants ... ok
[INFO] [stdout] test vm::tests_call::test_call_host_invalid_index ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_add_f64 ... ok
[INFO] [stdout] test vm::tests_global_vars::test_vm_has_global_vars ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_add_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_complex_program ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_empty_bytecode ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_consistent_with_print_bytecode ... ok
[INFO] [stdout] test vm::tests_global_vars::test_insert_and_get_value_var ... ok
[INFO] [stdout] test vm::tests_global_vars::test_insert_and_get_ptr_var ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_gte_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_arithmetic ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_conversion_instructions ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_jmp ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_jump ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_jump_missing_register ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_gt_f64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_load_const_value ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_load_const_value_missing_register ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_sub_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_type_conversion ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_jmp ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_jump_backward_if_false ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_jump_backward_if_true ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_jump_forward_if_false ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_jump_forward_if_true ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_load_const_slice ... ok
[INFO] [stdout] test vm::tests_bytecode_builder::test_factorial_loop_with_labels ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_gt_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_gte_f64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_all_f64_arithmetic ... ok
[INFO] [stdout] test vm::tests::test_f64_comparison_ops ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_extreme_values ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_all_jump_instructions ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_all_i64_arithmetic ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_load_const_value ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_load_f64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_load_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_lt_f64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_lt_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_lte_f64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_incomplete_load_const_slice ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_lte_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_mul_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_negative_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_mul_f64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_type_conversions ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_unknown_opcode ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_sub_i64 ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_output_ends_with_newline ... ok
[INFO] [stdout] test vm::tests_print_bytecode::test_format_special_f64_values ... ok
[INFO] [stdout] test vm::tests_registers::basic_get_set ... ok
[INFO] [stdout] test vm::tests_registers::default_zero_after_growth ... ok
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "1bb897f44e20a4f10c9a40bf974fb4c3be3de1ba3bb82d682dd590fc6c3d2e1b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1bb897f44e20a4f10c9a40bf974fb4c3be3de1ba3bb82d682dd590fc6c3d2e1b", kill_on_drop: false }`
[INFO] [stdout] 1bb897f44e20a4f10c9a40bf974fb4c3be3de1ba3bb82d682dd590fc6c3d2e1b