[INFO] cloning repository https://github.com/selaux/ruinfuck
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/selaux/ruinfuck" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fselaux%2Fruinfuck", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fselaux%2Fruinfuck'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d059ec7de1ec9cbaccec14918fc7cecee37d0f92
[INFO] testing selaux/ruinfuck against beta-2022-04-10 for beta-1.61-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fselaux%2Fruinfuck" "/workspace/builds/worker-38/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-38/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/selaux/ruinfuck on toolchain beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/selaux/ruinfuck
[INFO] finished tweaking git repo https://github.com/selaux/ruinfuck
[INFO] tweaked toml for git repo https://github.com/selaux/ruinfuck written to /workspace/builds/worker-38/source/Cargo.toml
[INFO] crate git repo https://github.com/selaux/ruinfuck already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 1aae55c56a36810328466c54c200ff39671f64be8804e1788b389812e5d7dfc1
[INFO] running `Command { std: "docker" "start" "-a" "1aae55c56a36810328466c54c200ff39671f64be8804e1788b389812e5d7dfc1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1aae55c56a36810328466c54c200ff39671f64be8804e1788b389812e5d7dfc1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1aae55c56a36810328466c54c200ff39671f64be8804e1788b389812e5d7dfc1", kill_on_drop: false }`
[INFO] [stdout] 1aae55c56a36810328466c54c200ff39671f64be8804e1788b389812e5d7dfc1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 84fed41460dc204142e0175d1e30494e968597197dafe0eaac6d7fa4f8df15ea
[INFO] running `Command { std: "docker" "start" "-a" "84fed41460dc204142e0175d1e30494e968597197dafe0eaac6d7fa4f8df15ea", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.65
[INFO] [stderr]    Compiling nix v0.14.1
[INFO] [stderr]    Compiling log v0.4.8
[INFO] [stderr]    Compiling memchr v2.2.1
[INFO] [stderr]    Compiling unicode-width v0.1.4
[INFO] [stderr]    Compiling utf8parse v0.1.1
[INFO] [stderr]    Compiling dirs-sys v0.3.4
[INFO] [stderr]    Compiling dirs v2.0.2
[INFO] [stderr]    Compiling rustyline v5.0.4
[INFO] [stderr]    Compiling ruinfuck v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.34s
[INFO] running `Command { std: "docker" "inspect" "84fed41460dc204142e0175d1e30494e968597197dafe0eaac6d7fa4f8df15ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "84fed41460dc204142e0175d1e30494e968597197dafe0eaac6d7fa4f8df15ea", kill_on_drop: false }`
[INFO] [stdout] 84fed41460dc204142e0175d1e30494e968597197dafe0eaac6d7fa4f8df15ea
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2bc02ce6353f4ff78df57fc47265d7f779e50e67335449d68d66b4dff8e71ed7
[INFO] running `Command { std: "docker" "start" "-a" "2bc02ce6353f4ff78df57fc47265d7f779e50e67335449d68d66b4dff8e71ed7", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling ruinfuck v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 17.61s
[INFO] [stderr]   Executable unittests src/main.rs (/opt/rustwide/target/debug/deps/ruinfuck-02eef87d90cc56b0)
[INFO] running `Command { std: "docker" "inspect" "2bc02ce6353f4ff78df57fc47265d7f779e50e67335449d68d66b4dff8e71ed7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2bc02ce6353f4ff78df57fc47265d7f779e50e67335449d68d66b4dff8e71ed7", kill_on_drop: false }`
[INFO] [stdout] 2bc02ce6353f4ff78df57fc47265d7f779e50e67335449d68d66b4dff8e71ed7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] abf66fb2cdc63073ff51796a8c979b6d8d557b45e4eeafe24dfa4d426b17a2d8
[INFO] running `Command { std: "docker" "start" "-a" "abf66fb2cdc63073ff51796a8c979b6d8d557b45e4eeafe24dfa4d426b17a2d8", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 4.08s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/ruinfuck-02eef87d90cc56b0)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test analyzer::tests::it_should_return_empty_results ... ok
[INFO] [stdout] test analyzer::tests::it_should_return_results_for_a_single_node ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_imbalanced_inc_nodes ... ok
[INFO] [stdout] test analyzer::tests::it_should_return_results_for_nested_conditionals ... ok
[INFO] [stdout] test analyzer::tests::it_should_return_results_for_multiple_different_nodes ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_imbalanced_inc_nodes_2 ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_imbalanced_inc_nodes_4 ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_non_moving_in_nodes ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_imbalanced_non_moving_nodes ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_imbalanced_inc_nodes_3 ... ok
[INFO] [stdout] test analyzer::tests::it_should_return_results_for_empty_conditionals ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_non_moving_inc_nodes ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_non_moving_nodes ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_scan_loops ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_simple_loops ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_to_negative_offsets ... ok
[INFO] [stdout] test optimizer::tests::it_should_not_optimize_operators_with_different_offsets ... ok
[INFO] [stdout] test optimizer::tests::it_should_collapse_to_positive_offsets ... ok
[INFO] [stdout] test optimizer::tests::it_should_defer_movement ... ok
[INFO] [stdout] test optimizer::tests::it_should_optimize_assignment_loops ... ok
[INFO] [stdout] test optimizer::tests::it_should_optimize_away_comments ... ok
[INFO] [stdout] test optimizer::tests::it_should_optimize_zero_loops ... ok
[INFO] [stdout] test optimizer::tests::it_should_optimize_away_repeated_operators ... ok
[INFO] [stdout] test parser::tests::it_should_parse_an_conditional_with_instructions ... ok
[INFO] [stdout] test parser::tests::it_should_parse_instructions ... ok
[INFO] [stdout] test parser::tests::it_should_parse_nested_conditionals ... ok
[INFO] [stdout] test parser::tests::it_should_return_a_missing_delimiter_error ... ok
[INFO] [stdout] test parser::tests::it_should_return_a_unmatched_delimiter_error ... ok
[INFO] [stdout] test parser::tests::it_should_parse_an_empty_conditional ... ok
[INFO] [stdout] test tests::it_should_return_parser_errors_when_running_code ... ok
[INFO] [stdout] test vm::tests::it_should_assign_cells ... ok
[INFO] [stdout] test vm::tests::it_should_assign_cells_at_underflowing_offset ... ok
[INFO] [stdout] test vm::tests::it_should_assign_cells_at_offset ... ok
[INFO] [stdout] test vm::tests::it_should_decrement_cells ... ok
[INFO] [stdout] test vm::tests::it_should_assign_cells_at_offset_and_move_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_assign_cells_at_overflowing_offset ... ok
[INFO] [stdout] test vm::tests::it_should_decrement_cells_at_overflowing_offset ... ok
[INFO] [stdout] test vm::tests::it_should_decrement_cells_at_offset ... ok
[INFO] [stdout] test vm::tests::it_should_increment_cells_at_offset ... ok
[INFO] [stdout] test vm::tests::it_should_decrement_cells_at_offset_and_move_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_multiply_cells_at_offset ... ok
[INFO] [stdout] test vm::tests::it_should_multiply_cells ... ok
[INFO] [stdout] test vm::tests::it_should_increment_cells_at_underflowing_offset ... ok
[INFO] [stdout] test vm::tests::it_should_not_run_nested_code_if_condition_is_false ... ok
[INFO] [stdout] test vm::tests::it_should_overflow_cells ... ok
[INFO] [stdout] test vm::tests::it_should_increment_cells_at_offset_and_move_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_overflow_the_data_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_read_from_stdin_with_offset_and_move_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_multiply_cells_at_offset_and_move_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_read_from_stdin ... ok
[INFO] [stdout] test vm::tests::it_should_decrement_the_data_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_scan_left ... ok
[INFO] [stdout] test vm::tests::it_should_scan_right ... ok
[INFO] [stdout] test vm::tests::it_should_underflow_the_data_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_decrement_cells_at_underflowing_offset ... ok
[INFO] [stdout] test vm::tests::it_should_increment_cells_at_overflowing_offset ... ok
[INFO] [stdout] test vm::tests::it_should_write_to_stdout_with_offset_and_move_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_increment_cells ... ok
[INFO] [stdout] test vm::tests::it_should_increment_the_data_pointer ... ok
[INFO] [stdout] test vm::tests::it_should_read_from_stdin_with_offset ... ok
[INFO] [stdout] test vm::tests::it_should_underflow_cells ... ok
[INFO] [stdout] test vm::tests::it_should_scan_left_with_interval ... ok
[INFO] [stdout] test vm::tests::it_should_scan_right_with_interval ... ok
[INFO] [stdout] test vm::tests::it_should_write_to_stdout ... ok
[INFO] [stdout] test vm::tests::it_should_run_nested_code_if_condition_is_true ... ok
[INFO] [stdout] test vm::tests::it_should_write_to_stdout_with_offset ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "abf66fb2cdc63073ff51796a8c979b6d8d557b45e4eeafe24dfa4d426b17a2d8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "abf66fb2cdc63073ff51796a8c979b6d8d557b45e4eeafe24dfa4d426b17a2d8", kill_on_drop: false }`
[INFO] [stdout] abf66fb2cdc63073ff51796a8c979b6d8d557b45e4eeafe24dfa4d426b17a2d8