[INFO] fetching crate vtashkov-bf 1.0.1...
[INFO] testing vtashkov-bf-1.0.1 against 1.90.0 for beta-1.91-3
[INFO] extracting crate vtashkov-bf 1.0.1 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate vtashkov-bf 1.0.1
[INFO] finished tweaking crates.io crate vtashkov-bf 1.0.1
[INFO] tweaked toml for crates.io crate vtashkov-bf 1.0.1 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vtashkov-bf 1.0.1 on toolchain 1.90.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate vtashkov-bf 1.0.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded proc-macro2 v1.0.84
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+1.90.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a24ebcea6cbd96010c1fdaf30463cc00c2bd13312a54dfc1830134e35209184a
[INFO] running `Command { std: "docker" "start" "-a" "a24ebcea6cbd96010c1fdaf30463cc00c2bd13312a54dfc1830134e35209184a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a24ebcea6cbd96010c1fdaf30463cc00c2bd13312a54dfc1830134e35209184a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a24ebcea6cbd96010c1fdaf30463cc00c2bd13312a54dfc1830134e35209184a", kill_on_drop: false }`
[INFO] [stdout] a24ebcea6cbd96010c1fdaf30463cc00c2bd13312a54dfc1830134e35209184a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5c415bc0f454ac96ff9a2a0823b35a961623363b6aa82beb9c5296404985df45
[INFO] running `Command { std: "docker" "start" "-a" "5c415bc0f454ac96ff9a2a0823b35a961623363b6aa82beb9c5296404985df45", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.84
[INFO] [stderr]    Compiling anstyle-query v1.0.3
[INFO] [stderr]    Compiling anstyle-parse v0.2.4
[INFO] [stderr]    Compiling anstream v0.6.14
[INFO] [stderr]    Compiling clap_builder v4.5.2
[INFO] [stderr]    Compiling quote v1.0.36
[INFO] [stderr]    Compiling syn v2.0.66
[INFO] [stderr]    Compiling clap_derive v4.5.4
[INFO] [stderr]    Compiling clap v4.5.4
[INFO] [stderr]    Compiling vtashkov-bf v1.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.68s
[INFO] running `Command { std: "docker" "inspect" "5c415bc0f454ac96ff9a2a0823b35a961623363b6aa82beb9c5296404985df45", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c415bc0f454ac96ff9a2a0823b35a961623363b6aa82beb9c5296404985df45", kill_on_drop: false }`
[INFO] [stdout] 5c415bc0f454ac96ff9a2a0823b35a961623363b6aa82beb9c5296404985df45
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 53b07d0d549f697a4c2aa317632673015f2572b242392e414db88ce4c4dd58a8
[INFO] running `Command { std: "docker" "start" "-a" "53b07d0d549f697a4c2aa317632673015f2572b242392e414db88ce4c4dd58a8", kill_on_drop: false }`
[INFO] [stderr]    Compiling vtashkov-bf v1.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.07s
[INFO] running `Command { std: "docker" "inspect" "53b07d0d549f697a4c2aa317632673015f2572b242392e414db88ce4c4dd58a8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "53b07d0d549f697a4c2aa317632673015f2572b242392e414db88ce4c4dd58a8", kill_on_drop: false }`
[INFO] [stdout] 53b07d0d549f697a4c2aa317632673015f2572b242392e414db88ce4c4dd58a8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] cbdac66883f406a1746b5bf9fc62aebe8898b3f713d270683d3d78cda8cc54c6
[INFO] running `Command { std: "docker" "start" "-a" "cbdac66883f406a1746b5bf9fc62aebe8898b3f713d270683d3d78cda8cc54c6", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vtashkov_bf-d3a4373b2a1b0091)
[INFO] [stdout] 
[INFO] [stdout] running 51 tests
[INFO] [stdout] test interpreter::tests::interpreter_executes_embedded_no_end_loops ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_embedded_no_end_loop ... ok
[INFO] [stdout] test interpreter::tests::interpreter_can_be_created ... ok
[INFO] [stdout] test interpreter::tests::interpreter_does_not_change_anything_if_no_input_value ... ok
[INFO] [stdout] test interpreter::tests::interpreter_decrements_cell ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_double_embedded_loops ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_embedded_loops ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_loop_once_if_current_cell_is_non_zero ... ok
[INFO] [stdout] test interpreter::tests::interpreter_can_execute_code ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_double_embedded_no_end_loops ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_no_embedded_end_loop ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_loop_twice_if_current_cell_is_two ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_only_end_loop ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_two_embedded_loops ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_two_embedded_no_end_loop ... ok
[INFO] [stdout] test interpreter::tests::interpreter_goes_back_to_first_cell_after_reaching_the_end ... ok
[INFO] [stdout] test interpreter::tests::interpreter_goes_back_to_the_end_if_going_back_from_the_first_cell ... ok
[INFO] [stdout] test interpreter::tests::interpreter_ignores_non_instructions ... ok
[INFO] [stdout] test interpreter::tests::interpreter_increments_cell ... ok
[INFO] [stdout] test interpreter::tests::interpreter_moves_back_and_forth ... ok
[INFO] [stdout] test interpreter::tests::interpreter_inputs_and_outputs_value ... ok
[INFO] [stdout] test interpreter::tests::interpreter_moves_to_next_cell ... ok
[INFO] [stdout] test interpreter::tests::interpreter_outputs_default_cell_value_of_zero ... ok
[INFO] [stdout] test memory::tests::memory_can_be_created ... ok
[INFO] [stdout] test memory::tests::memory_can_move_to_next_cell ... ok
[INFO] [stdout] test memory::tests::memory_can_move_to_previous_cell ... ok
[INFO] [stdout] test interpreter::tests::interpreter_inputs_and_outputs_multiple_value ... ok
[INFO] [stdout] test memory::tests::memory_cell_can_be_incremented ... ok
[INFO] [stdout] test interpreter::tests::interpreter_skips_loop_if_current_cell_is_zero ... ok
[INFO] [stdout] test memory::tests::memory_can_be_cleared ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_initialized_to_zero ... ok
[INFO] [stdout] test memory::tests::memory_cell_can_be_accessed ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_two_after_incrementing_twice_the_initial_value ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_one_after_decrementing_from_two ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_wrapping_after_decrementing_zero ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_one_after_incrementing_the_initial_value ... ok
[INFO] [stdout] test memory::tests::memory_cell_can_be_written_to ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_zero_after_decrementing_twice_from_two ... ok
[INFO] [stdout] test memory::tests::memory_cell_is_wrapping_after_incrementing_the_max_value ... ok
[INFO] [stdout] test memory::tests::memory_cell_can_be_decremented ... ok
[INFO] [stdout] test memory::tests::memory_cell_preserves_the_written_value ... ok
[INFO] [stdout] test memory::tests::memory_cells_go_from_the_begining_after_next_on_the_last_cell ... ok
[INFO] [stdout] test memory::tests::memory_cells_go_to_the_end_after_previous_on_the_first_cell ... ok
[INFO] [stdout] test memory::tests::memory_cells_are_initialized_to_zero ... ok
[INFO] [stdout] test memory::tests::memory_cells_preserve_value_after_next ... ok
[INFO] [stdout] test memory::tests::memory_cells_preserve_value_after_previous ... ok
[INFO] [stdout] test tests::run_cmd_can_be_invoked ... ok
[INFO] [stdout] test tests::run_cmd_with_wrong_input_file_returns_no_such_file ... ok
[INFO] [stdout] test tests::run_cmd_can_execute_hello_world ... ok
[INFO] [stdout] test interpreter::tests::interpreter_executes_no_end_loop ... ok
[INFO] [stdout] test interpreter::tests::interpreter_increments_and_decrements_cell_multiple_times ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/vtashkov_bf-a46c5529ecc094ae)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 51 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/hello_world_test.rs (/opt/rustwide/target/debug/deps/hello_world_test-b957ad37f75fd72d)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test it_can_execute_hello_world ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vtashkov_bf
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - interpreter (line 29) ... ok
[INFO] [stdout] test src/lib.rs - (line 7) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.46s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "cbdac66883f406a1746b5bf9fc62aebe8898b3f713d270683d3d78cda8cc54c6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cbdac66883f406a1746b5bf9fc62aebe8898b3f713d270683d3d78cda8cc54c6", kill_on_drop: false }`
[INFO] [stdout] cbdac66883f406a1746b5bf9fc62aebe8898b3f713d270683d3d78cda8cc54c6