[INFO] fetching crate miden-processor 0.5.0...
[INFO] testing miden-processor-0.5.0 against 1.69.0 for beta-1.70-1
[INFO] extracting crate miden-processor 0.5.0 into /workspace/builds/worker-0-tc1/source
[INFO] validating manifest of crates.io crate miden-processor 0.5.0 on toolchain 1.69.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.69.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate miden-processor 0.5.0
[INFO] finished tweaking crates.io crate miden-processor 0.5.0
[INFO] tweaked toml for crates.io crate miden-processor 0.5.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" __CARGO_TEST_CHANNEL_OVERRIDE_DO_NOT_USE_THIS="nightly" "/workspace/cargo-home/bin/cargo" "+1.69.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.69.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded winter-crypto v0.6.2
[INFO] [stderr]   Downloaded miden-crypto v0.2.0
[INFO] [stderr]   Downloaded logtest v2.0.0
[INFO] [stderr]   Downloaded miden-core v0.5.0
[INFO] [stderr]   Downloaded winter-math v0.6.2
[INFO] [stderr]   Downloaded winter-rand-utils v0.6.2
[INFO] [stderr]   Downloaded winter-utils v0.6.2
[INFO] [stderr]   Downloaded miden-assembly v0.5.0
[INFO] [stderr]   Downloaded winter-air v0.6.2
[INFO] [stderr]   Downloaded winter-fri v0.6.2
[INFO] [stderr]   Downloaded winter-prover v0.6.2
[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:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ef7fdd5e5e0c6275044792213243b8b84c82adea0a119db85c7d2e5e7dd745b1
[INFO] running `Command { std: "docker" "start" "-a" "ef7fdd5e5e0c6275044792213243b8b84c82adea0a119db85c7d2e5e7dd745b1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ef7fdd5e5e0c6275044792213243b8b84c82adea0a119db85c7d2e5e7dd745b1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ef7fdd5e5e0c6275044792213243b8b84c82adea0a119db85c7d2e5e7dd745b1", kill_on_drop: false }`
[INFO] [stdout] ef7fdd5e5e0c6275044792213243b8b84c82adea0a119db85c7d2e5e7dd745b1
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3166b63ff119da22bbffb9dd0f3243b0cdb50e0a3f43201763b15992200307b4
[INFO] running `Command { std: "docker" "start" "-a" "3166b63ff119da22bbffb9dd0f3243b0cdb50e0a3f43201763b15992200307b4", kill_on_drop: false }`
[INFO] [stderr]    Compiling subtle v2.4.1
[INFO] [stderr]    Compiling winter-utils v0.6.2
[INFO] [stderr]    Compiling constant_time_eq v0.2.5
[INFO] [stderr]    Compiling arrayref v0.3.7
[INFO] [stderr]    Compiling keccak v0.1.3
[INFO] [stderr]    Compiling arrayvec v0.7.2
[INFO] [stderr]    Compiling value-bag v1.0.0-alpha.9
[INFO] [stderr]    Compiling blake3 v1.3.3
[INFO] [stderr]    Compiling log v0.4.17
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling digest v0.10.6
[INFO] [stderr]    Compiling winter-math v0.6.2
[INFO] [stderr]    Compiling sha3 v0.10.7
[INFO] [stderr]    Compiling winter-crypto v0.6.2
[INFO] [stderr]    Compiling winter-fri v0.6.2
[INFO] [stderr]    Compiling miden-crypto v0.2.0
[INFO] [stderr]    Compiling winter-air v0.6.2
[INFO] [stderr]    Compiling miden-core v0.5.0
[INFO] [stderr]    Compiling ctor v0.1.26
[INFO] [stderr]    Compiling winter-prover v0.6.2
[INFO] [stderr]    Compiling miden-processor v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.50s
[INFO] running `Command { std: "docker" "inspect" "3166b63ff119da22bbffb9dd0f3243b0cdb50e0a3f43201763b15992200307b4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3166b63ff119da22bbffb9dd0f3243b0cdb50e0a3f43201763b15992200307b4", kill_on_drop: false }`
[INFO] [stdout] 3166b63ff119da22bbffb9dd0f3243b0cdb50e0a3f43201763b15992200307b4
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6f16880d92bebe7be43916373bc033061b04874bc32394003d3b1b748d596e5d
[INFO] running `Command { std: "docker" "start" "-a" "6f16880d92bebe7be43916373bc033061b04874bc32394003d3b1b748d596e5d", kill_on_drop: false }`
[INFO] [stderr]    Compiling hashbrown v0.12.3
[INFO] [stderr]    Compiling winnow v0.4.4
[INFO] [stderr]    Compiling toml_datetime v0.6.1
[INFO] [stderr]    Compiling once_cell v1.17.1
[INFO] [stderr]    Compiling indexmap v1.9.3
[INFO] [stderr]    Compiling winter-rand-utils v0.6.2
[INFO] [stderr]    Compiling logtest v2.0.0
[INFO] [stderr]    Compiling toml_edit v0.19.8
[INFO] [stderr]    Compiling proc-macro-crate v1.3.1
[INFO] [stderr]    Compiling num_enum_derive v0.5.11
[INFO] [stderr]    Compiling num_enum v0.5.11
[INFO] [stderr]    Compiling miden-assembly v0.5.0
[INFO] [stderr]    Compiling miden-processor v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 19.72s
[INFO] running `Command { std: "docker" "inspect" "6f16880d92bebe7be43916373bc033061b04874bc32394003d3b1b748d596e5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6f16880d92bebe7be43916373bc033061b04874bc32394003d3b1b748d596e5d", kill_on_drop: false }`
[INFO] [stdout] 6f16880d92bebe7be43916373bc033061b04874bc32394003d3b1b748d596e5d
[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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5ff2268d62e97bff2cde7d5815dd6ed5b47097566f13b8d0ca110b04b60e07ed" "/opt/rustwide/cargo-home/bin/cargo" "+1.69.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 727b6f987e70daf394d3f1ec944812a03de677cd17dfab4bc7db32acf956c484
[INFO] running `Command { std: "docker" "start" "-a" "727b6f987e70daf394d3f1ec944812a03de677cd17dfab4bc7db32acf956c484", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/miden_processor-4cbb128508b2a1b3)
[INFO] [stdout] 
[INFO] [stdout] running 125 tests
[INFO] [stdout] test chiplets::kernel_rom::tests::kernel_rom_empty ... ok
[INFO] [stdout] test chiplets::bitwise::tests::bitwise_init ... ok
[INFO] [stdout] test chiplets::kernel_rom::tests::kernel_rom_no_access ... ok
[INFO] [stdout] test chiplets::kernel_rom::tests::kernel_rom_invalid_access ... ok
[INFO] [stdout] test chiplets::kernel_rom::tests::kernel_rom_with_access ... ok
[INFO] [stdout] test chiplets::memory::tests::mem_read ... ok
[INFO] [stdout] test chiplets::memory::tests::mem_init ... ok
[INFO] [stdout] test chiplets::memory::tests::mem_get_state_at ... ok
[INFO] [stdout] test chiplets::hasher::tests::hash_memoization_control_blocks ... ok
[INFO] [stdout] test chiplets::bitwise::tests::bitwise_xor ... ok
[INFO] [stdout] test chiplets::memory::tests::mem_write_read ... ok
[INFO] [stdout] test chiplets::memory::tests::mem_write ... ok
[INFO] [stdout] test chiplets::memory::tests::mem_multi_context ... ok
[INFO] [stdout] test chiplets::hasher::tests::hash_memoization_span_blocks ... ok
[INFO] [stdout] test chiplets::hasher::tests::hasher_build_merkle_root ... ok
[INFO] [stdout] test chiplets::tests::memory_chiplet_trace ... ok
[INFO] [stdout] test chiplets::hasher::tests::hasher_update_merkle_root ... ok
[INFO] [stdout] test chiplets::tests::stacked_chiplet_trace ... ok
[INFO] [stdout] test chiplets::hasher::tests::hasher_permute ... ok
[INFO] [stdout] test decoder::tests::loop_block_repeat ... ok
[INFO] [stdout] test decoder::tests::join_block ... ok
[INFO] [stdout] test decoder::tests::set_user_op_helpers_many ... ok
[INFO] [stdout] test chiplets::tests::hasher_chiplet_trace ... ok
[INFO] [stdout] test chiplets::tests::bitwise_chiplet_trace ... ok
[INFO] [stdout] test chiplets::bitwise::tests::bitwise_multiple ... ok
[INFO] [stdout] test decoder::tests::span_block_with_respan ... ok
[INFO] [stdout] test decorators::tests::inject_merkle_node ... ok
[INFO] [stdout] test decoder::tests::span_block_one_group ... ok
[INFO] [stdout] test operations::crypto_ops::tests::op_mpverify ... ok
[INFO] [stdout] test chiplets::bitwise::tests::bitwise_and ... ok
[INFO] [stdout] test decoder::tests::loop_block_skip ... ok
[INFO] [stdout] test operations::crypto_ops::tests::op_mrupdate ... ok
[INFO] [stdout] test operations::field_ops::tests::op_eq ... ok
[INFO] [stdout] test operations::crypto_ops::tests::op_mrupdate_merge_subtree ... ok
[INFO] [stdout] test operations::field_ops::tests::op_eqz ... ok
[INFO] [stdout] test operations::ext2_ops::tests::op_ext2mul ... ok
[INFO] [stdout] test operations::field_ops::tests::op_add ... ok
[INFO] [stdout] test decoder::tests::span_block_small ... ok
[INFO] [stdout] test decoder::tests::call_block ... ok
[INFO] [stdout] test decoder::tests::span_block ... ok
[INFO] [stdout] test operations::field_ops::tests::op_neg ... ok
[INFO] [stdout] test operations::field_ops::tests::op_not ... ok
[INFO] [stdout] test operations::field_ops::tests::op_incr ... ok
[INFO] [stdout] test operations::field_ops::tests::op_expacc ... ok
[INFO] [stdout] test operations::field_ops::tests::op_and ... ok
[INFO] [stdout] test operations::fri_ops::tests::fold4 ... ok
[INFO] [stdout] test operations::io_ops::tests::op_mload ... ok
[INFO] [stdout] test operations::field_ops::tests::op_inv ... ok
[INFO] [stdout] test operations::io_ops::tests::op_advpop ... ok
[INFO] [stdout] test operations::io_ops::tests::op_push ... ok
[INFO] [stdout] test operations::io_ops::tests::op_mstore ... ok
[INFO] [stdout] test operations::fri_ops::tests::constants ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_drop ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_dup ... ok
[INFO] [stdout] test operations::io_ops::tests::op_mstorew ... ok
[INFO] [stdout] test operations::io_ops::tests::op_pipe ... ok
[INFO] [stdout] test operations::field_ops::tests::op_mul ... ok
[INFO] [stdout] test operations::io_ops::tests::op_mstream ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_movdn ... ok
[INFO] [stdout] test operations::field_ops::tests::op_or ... ok
[INFO] [stdout] test operations::io_ops::tests::op_mloadw ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_cswap ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_movup ... ok
[INFO] [stdout] test decoder::tests::split_block_true ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_swapw3 ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_pad ... ok
[INFO] [stdout] test operations::sys_ops::tests::op_fmpadd ... ok
[INFO] [stdout] test operations::sys_ops::tests::op_assert ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_swap ... ok
[INFO] [stdout] test operations::fri_ops::tests::op_fri_ext2fold4 ... ok
[INFO] [stdout] test operations::crypto_ops::tests::op_hperm ... ok
[INFO] [stdout] test operations::sys_ops::tests::op_clk ... ok
[INFO] [stdout] test operations::io_ops::tests::op_advpopw ... ok
[INFO] [stdout] test operations::sys_ops::tests::op_sdepth ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_swapw ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32div ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_cswapw ... ok
[INFO] [stdout] test decoder::tests::loop_block ... ok
[INFO] [stdout] test operations::stack_ops::tests::op_swapw2 ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32mul ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32xor ... ok
[INFO] [stdout] test range::tests::range_checks ... ok
[INFO] [stdout] test stack::tests::initialize_overflow ... ok
[INFO] [stdout] test stack::tests::shift_left ... ok
[INFO] [stdout] test stack::tests::generate_trace ... ok
[INFO] [stdout] test stack::tests::initialize ... ok
[INFO] [stdout] test stack::tests::shift_right ... ok
[INFO] [stdout] test stack::tests::start_restore_context ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32add ... ok
[INFO] [stdout] test range::tests::range_checks_rand ... ok
[INFO] [stdout] test decoder::tests::split_block_false ... ok
[INFO] [stdout] test decoder::tests::syscall_block ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32and ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32split ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p1_join ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32assert2 ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32sub ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32madd ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p1_loop_with_repeat ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p2_split_true ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p2_join ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p2_split_false ... ok
[INFO] [stdout] test trace::tests::chiplets::bitwise::b_aux_trace_bitwise ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p2_span_with_respan ... ok
[INFO] [stdout] test operations::sys_ops::tests::op_fmpupdate ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p1_split ... ok
[INFO] [stdout] test trace::tests::chiplets::memory::b_aux_trace_mem ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p1_span_with_respan ... ok
[INFO] [stdout] test trace::tests::hasher::hasher_p1_mp_verify ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p3_trace_empty_table ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p3_trace_two_batches ... ok
[INFO] [stdout] test trace::tests::chiplets::hasher::b_chip_merge ... ok
[INFO] [stdout] test trace::tests::chiplets::hasher::b_chip_permutation ... ok
[INFO] [stdout] test trace::tests::chiplets::hasher::b_chip_mpverify ... ok
[INFO] [stdout] test trace::tests::hasher::hasher_p1_mr_update ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p3_trace_one_batch ... ok
[INFO] [stdout] test trace::tests::chiplets::hasher::b_chip_span ... ok
[INFO] [stdout] test trace::decoder::tests::decoder_p2_loop_with_repeat ... ok
[INFO] [stdout] test trace::tests::range::p0_trace ... ok
[INFO] [stdout] test trace::tests::range::q_trace ... ok
[INFO] [stdout] test operations::u32_ops::tests::op_u32add3 ... ok
[INFO] [stdout] test trace::tests::chiplets::hasher::b_chip_span_with_respan ... ok
[INFO] [stdout] test trace::tests::range::p1_trace_mem ... ok
[INFO] [stdout] test trace::tests::range::p1_trace_stack ... ok
[INFO] [stdout] test trace::tests::stack::p1_trace ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 125 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.04s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "727b6f987e70daf394d3f1ec944812a03de677cd17dfab4bc7db32acf956c484", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "727b6f987e70daf394d3f1ec944812a03de677cd17dfab4bc7db32acf956c484", kill_on_drop: false }`
[INFO] [stdout] 727b6f987e70daf394d3f1ec944812a03de677cd17dfab4bc7db32acf956c484
