[INFO] fetching crate bfc 1.9.0... [INFO] testing bfc-1.9.0 against beta-2022-04-10 for beta-1.61-1 [INFO] extracting crate bfc 1.9.0 into /workspace/builds/worker-1/source [INFO] validating manifest of crates.io crate bfc 1.9.0 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 crates.io crate bfc 1.9.0 [INFO] finished tweaking crates.io crate bfc 1.9.0 [INFO] tweaked toml for crates.io crate bfc 1.9.0 written to /workspace/builds/worker-1/source/Cargo.toml [INFO] crate crates.io crate bfc 1.9.0 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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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] 0563e4b83bc3d1f2c2cfd6018ebff12076607b9dfff0f95dc5be9e746042bb5d [INFO] running `Command { std: "docker" "start" "-a" "0563e4b83bc3d1f2c2cfd6018ebff12076607b9dfff0f95dc5be9e746042bb5d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "0563e4b83bc3d1f2c2cfd6018ebff12076607b9dfff0f95dc5be9e746042bb5d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0563e4b83bc3d1f2c2cfd6018ebff12076607b9dfff0f95dc5be9e746042bb5d", kill_on_drop: false }` [INFO] [stdout] 0563e4b83bc3d1f2c2cfd6018ebff12076607b9dfff0f95dc5be9e746042bb5d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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] fd6f36043caac2811af73c867d8eb1b75eecfaa67a3a3a37fe479d08758c5970 [INFO] running `Command { std: "docker" "start" "-a" "fd6f36043caac2811af73c867d8eb1b75eecfaa67a3a3a37fe479d08758c5970", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling libc v0.2.79 [INFO] [stderr] Compiling memchr v2.3.3 [INFO] [stderr] Compiling getrandom v0.1.15 [INFO] [stderr] Compiling regex-syntax v0.6.20 [INFO] [stderr] Compiling cc v1.0.61 [INFO] [stderr] Compiling ppv-lite86 v0.2.9 [INFO] [stderr] Compiling getopts v0.2.21 [INFO] [stderr] Compiling aho-corasick v0.7.14 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling colored v2.0.0 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling regex v1.4.1 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling tempfile v3.1.0 [INFO] [stderr] Compiling llvm-sys v100.2.0 [INFO] [stderr] Compiling bfc v1.9.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 13.68s [INFO] running `Command { std: "docker" "inspect" "fd6f36043caac2811af73c867d8eb1b75eecfaa67a3a3a37fe479d08758c5970", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "fd6f36043caac2811af73c867d8eb1b75eecfaa67a3a3a37fe479d08758c5970", kill_on_drop: false }` [INFO] [stdout] fd6f36043caac2811af73c867d8eb1b75eecfaa67a3a3a37fe479d08758c5970 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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] 69adeab1436cfd68d36d0d09f873018d345dfff2580df36de6b7f967c2a05a37 [INFO] running `Command { std: "docker" "start" "-a" "69adeab1436cfd68d36d0d09f873018d345dfff2580df36de6b7f967c2a05a37", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling log v0.4.11 [INFO] [stderr] Compiling pretty_assertions v0.6.1 [INFO] [stderr] Compiling env_logger v0.7.1 [INFO] [stderr] Compiling quickcheck v0.9.2 [INFO] [stderr] Compiling bfc v1.9.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 6.36s [INFO] [stderr] Executable unittests src/main.rs (/opt/rustwide/target/debug/deps/bfc-3e1c12860bc0ea77) [INFO] running `Command { std: "docker" "inspect" "69adeab1436cfd68d36d0d09f873018d345dfff2580df36de6b7f967c2a05a37", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "69adeab1436cfd68d36d0d09f873018d345dfff2580df36de6b7f967c2a05a37", kill_on_drop: false }` [INFO] [stdout] 69adeab1436cfd68d36d0d09f873018d345dfff2580df36de6b7f967c2a05a37 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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] 8ea88a329c8e837c9d4931cc6c92341128e437812f09c0189bc32fa66673e4ef [INFO] running `Command { std: "docker" "start" "-a" "8ea88a329c8e837c9d4931cc6c92341128e437812f09c0189bc32fa66673e4ef", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.18s [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/bfc-3e1c12860bc0ea77) [INFO] [stdout] [INFO] [stdout] running 158 tests [INFO] [stdout] test bfir::parse_comment ... ok [INFO] [stdout] test bfir::parse_decrement ... ok [INFO] [stdout] test bfir::parse_complex_loop ... ok [INFO] [stdout] test bfir::parse_empty_loop ... ok [INFO] [stdout] test bfir::parse_increment ... ok [INFO] [stdout] test bfir::parse_pointer_decrement ... ok [INFO] [stdout] test bfir::parse_pointer_increment ... ok [INFO] [stdout] test bfir::parse_read ... ok [INFO] [stdout] test bfir::parse_simple_loop ... ok [INFO] [stdout] test bfir::parse_unbalanced_loop ... ok [INFO] [stdout] test bfir::parse_write ... ok [INFO] [stdout] test bfir::test_combine_order ... ok [INFO] [stdout] test bfir::test_combine_pos ... ok [INFO] [stdout] test bfir::test_combine_pos_not_consecutive ... ok [INFO] [stdout] test bfir::test_combine_pos_overlap ... ok [INFO] [stdout] test bounds::excessive_bounds_truncated ... ok [INFO] [stdout] test bounds::increment_offset_bounds ... ok [INFO] [stdout] test bounds::loop_with_no_net_movement ... ok [INFO] [stdout] test bounds::multiple_ptr_increment_bounds ... ok [INFO] [stdout] test bounds::multiply_move_backwards_bounds ... ok [INFO] [stdout] test bounds::multiply_move_bounds ... ok [INFO] [stdout] test bounds::multiply_move_bounds_are_relative ... ok [INFO] [stdout] test bounds::one_cell_bounds ... ok [INFO] [stdout] test bounds::ptr_increment_bounds ... ok [INFO] [stdout] test bounds::ptr_increment_sequence_bounds ... ok [INFO] [stdout] test bounds::set_offset_bounds ... ok [INFO] [stdout] test bounds::unbounded_movement ... ok [INFO] [stdout] test executable_name_b ... ok [INFO] [stdout] test executable_name_relative_path ... ok [INFO] [stdout] test executable_name_bf ... ok [INFO] [stdout] test execution::cant_evaluate_inputs ... ok [INFO] [stdout] test execution::decrement_executed ... ok [INFO] [stdout] test execution::execute_read_with_dummy_value ... ok [INFO] [stdout] test execution::execute_read_with_dummy_value_nested_loop ... ok [INFO] [stdout] test execution::increment_executed ... ok [INFO] [stdout] test execution::increment_wraps ... ok [INFO] [stdout] test execution::limit_to_steps_specified ... ok [INFO] [stdout] test execution::loop_up_to_step_limit ... ok [INFO] [stdout] test execution::loop_executed ... ok [INFO] [stdout] test execution::loop_with_read_body ... ok [INFO] [stdout] test execution::multiply_move_executed ... ok [INFO] [stdout] test execution::multiply_move_when_current_cell_is_zero ... ok [INFO] [stdout] test execution::multiply_move_offset_too_low ... ok [INFO] [stdout] test execution::multiply_move_wrapping ... ok [INFO] [stdout] test execution::partially_execute_complete_toplevel_loop ... ok [INFO] [stdout] test execution::partially_execute_up_to_runtime_value ... ok [INFO] [stdout] test execution::partially_execute_up_to_step_limit ... ok [INFO] [stdout] test execution::ptr_out_of_range ... ok [INFO] [stdout] test execution::ptr_increment_executed ... ok [INFO] [stdout] test execution::set_executed ... ok [INFO] [stdout] test execution::set_wraps ... ok [INFO] [stdout] test execution::up_to_nonempty_infinite_loop ... ok [INFO] [stdout] test execution::write_executed ... ok [INFO] [stdout] test execution::up_to_infinite_loop_executed ... ok [INFO] [stdout] test peephole_tests::dont_combine_before_read_different_offset ... ok [INFO] [stdout] test peephole_tests::combine_set_sum_to_zero ... ok [INFO] [stdout] test peephole_tests::no_combine_before_read_after_multiply ... ok [INFO] [stdout] test peephole_tests::not_redundant_set_when_nonzero ... ok [INFO] [stdout] test peephole_tests::prev_mutate_consider_pointer_increment ... ok [INFO] [stdout] test peephole_tests::prev_mutate_ignore_write ... ok [INFO] [stdout] test peephole_tests::prev_mutate_increment_matching_offset ... ok [INFO] [stdout] test peephole_tests::prev_mutate_loop ... ok [INFO] [stdout] test peephole_tests::next_mutate_increment ... ok [INFO] [stdout] test peephole_tests::combine_set_and_increment_non_adjacent_instrs ... ok [INFO] [stdout] test peephole_tests::no_combine_before_read_after_write ... ok [INFO] [stdout] test peephole_tests::next_mutate_consider_pointer_increment ... ok [INFO] [stdout] test peephole_tests::prev_mutate_increment ... ok [INFO] [stdout] test peephole_tests::next_mutate_loop ... ok [INFO] [stdout] test peephole_tests::combine_increments_flat ... ok [INFO] [stdout] test peephole_tests::prev_mutate_multiply_ignore_offset ... ok [INFO] [stdout] test peephole_tests::combine_increments_remove_redundant ... ok [INFO] [stdout] test peephole_tests::prev_mutate_multiply_offset_matches ... ok [INFO] [stdout] test peephole_tests::prev_mutate_multiply_offset_doesnt_match ... ok [INFO] [stdout] test peephole_tests::dont_simplify_multiple_decrement_loop ... ok [INFO] [stdout] test peephole_tests::prev_mutate_ignores_offset_at_index ... ok [INFO] [stdout] test peephole_tests::prev_mutate_no_predecessors ... ok [INFO] [stdout] test peephole_tests::prev_mutate_set ... ok [INFO] [stdout] test peephole_tests::combine_increments_non_adjacent_instrs ... ok [INFO] [stdout] test peephole_tests::combine_before_read_not_consecutive ... ok [INFO] [stdout] test peephole_tests::combine_increment_sum_to_zero ... ok [INFO] [stdout] test peephole_tests::remove_dead_loops_after_set ... ok [INFO] [stdout] test peephole_tests::remove_dead_loops_nested ... ok [INFO] [stdout] test peephole_tests::remove_dead_loops_not_adjacent ... ok [INFO] [stdout] test peephole_tests::combine_increments_unrelated ... ok [INFO] [stdout] test peephole_tests::combine_increments_nested ... ok [INFO] [stdout] test peephole_tests::should_annotate_known_zero ... ok [INFO] [stdout] test llvm_tests::compile_empty_program ... ok [INFO] [stdout] test llvm_tests::compile_ptr_increment ... ok [INFO] [stdout] test llvm_tests::compile_write ... ok [INFO] [stdout] test llvm_tests::compile_static_outputs ... ok [INFO] [stdout] test llvm_tests::compile_increment ... ok [INFO] [stdout] test peephole_tests::pathological_optimisation_opportunity ... ok [INFO] [stdout] test llvm_tests::compile_start_instr_midway ... ok [INFO] [stdout] test llvm_tests::compile_loop ... ok [INFO] [stdout] test peephole_tests::combine_increments_after_sort ... ok [INFO] [stdout] test peephole_tests::remove_repeated_loops ... ok [INFO] [stdout] test llvm_tests::compile_set_with_offset ... ok [INFO] [stdout] test peephole_tests::quickcheck_combine_increments_remove_zero_any_offset ... ok [INFO] [stdout] test llvm_tests::respect_initial_cell_ptr ... ok [INFO] [stdout] test peephole_tests::should_annotate_known_zero_cleaned_up ... ok [INFO] [stdout] test peephole_tests::should_annotate_known_zero_nested ... ok [INFO] [stdout] test peephole_tests::should_combine_ptr_increments ... ok [INFO] [stdout] test llvm_tests::set_initial_cell_values ... ok [INFO] [stdout] test peephole_tests::should_extract_multiply_multiple_cells ... ok [INFO] [stdout] test peephole_tests::should_combine_before_read ... ok [INFO] [stdout] test peephole_tests::should_combine_before_read_nested ... ok [INFO] [stdout] test peephole_tests::should_extract_multiply_negative_number ... ok [INFO] [stdout] test peephole_tests::should_combine_set_and_set_nested ... ok [INFO] [stdout] test peephole_tests::quickcheck_should_combine_increment_and_set ... ok [INFO] [stdout] test peephole_tests::quickcheck_should_combine_set_and_increment ... ok [INFO] [stdout] test peephole_tests::quickcheck_sort_by_offset_pointer_increments ... ok [INFO] [stdout] test peephole_tests::should_extract_multiply_nested ... ok [INFO] [stdout] test peephole_tests::should_not_extract_multiply_without_decrement ... ok [INFO] [stdout] test peephole_tests::should_preserve_set_0_in_loop ... ok [INFO] [stdout] test peephole_tests::should_not_extract_multiply_net_movement ... ok [INFO] [stdout] test peephole_tests::should_not_extract_multiply_with_inner_loop ... ok [INFO] [stdout] test peephole_tests::should_not_extract_multiply_with_read ... ok [INFO] [stdout] test peephole_tests::should_not_extract_multiply_with_write ... ok [INFO] [stdout] test peephole_tests::should_not_extract_multiply_from_clear_loop ... ok [INFO] [stdout] test peephole_tests::should_remove_pure_code ... ok [INFO] [stdout] test peephole_tests::quickcheck_combine_set_and_set_different_offsets ... ok [INFO] [stdout] test peephole_tests::should_remove_redundant_set ... ok [INFO] [stdout] test peephole_tests::should_extract_multiply_simple ... ok [INFO] [stdout] test peephole_tests::simplify_nested_zeroing_loop ... ok [INFO] [stdout] test peephole_tests::should_remove_redundant_set_multiply ... ok [INFO] [stdout] test peephole_tests::simplify_zeroing_loop ... ok [INFO] [stdout] test peephole_tests::sort_by_offset_increment ... ok [INFO] [stdout] test peephole_tests::sort_by_offset_read ... ok [INFO] [stdout] test peephole_tests::sort_by_offset_remove_redundant ... ok [INFO] [stdout] test peephole_tests::sort_by_offset_increment_nested ... ok [INFO] [stdout] test execution::arithmetic_error_nested_loops ... ok [INFO] [stdout] test peephole_tests::quickcheck_combine_set_and_set ... ok [INFO] [stdout] test peephole_tests::quickcheck_combine_set_and_increment_different_offsets ... ok [INFO] [stdout] test peephole_tests::quickcheck_sort_by_offset_set ... ok [INFO] [stdout] test peephole_tests::quickcheck_combine_increment_and_set_different_offsets ... ok [INFO] [stdout] test execution::multiply_move_offset_too_high ... ok [INFO] [stdout] test bounds::quickcheck_highest_cell_index_in_bounds ... ok [INFO] [stdout] test peephole_tests::quickcheck_should_annotate_known_zero_at_start ... ok [INFO] [stdout] test peephole_tests::annotate_known_zero_idempotent ... ok [INFO] [stdout] test peephole_tests::quickcheck_optimize_should_decrease_size ... ok [INFO] [stdout] test execution::quickcheck_cell_ptr_in_bounds ... ok [INFO] [stdout] test peephole_tests::quickcheck_optimize_should_be_idempotent ... ok [INFO] [stdout] test soundness_tests::remove_pure_code_is_sound ... ok [INFO] [stdout] test soundness_tests::combine_before_read_is_sound ... ok [INFO] [stdout] test soundness_tests::simplify_loops_is_sound ... ok [INFO] [stdout] test soundness_tests::extract_multiply_is_sound ... ok [INFO] [stdout] test soundness_tests::sort_by_offset_is_sound ... ok [INFO] [stdout] test soundness_tests::test_overall_optimize_is_sound ... ok [INFO] [stdout] test soundness_tests::combine_set_and_increments_is_sound ... ok [INFO] [stdout] test soundness_tests::remove_dead_loops_is_sound ... ok [INFO] [stdout] test soundness_tests::annotate_known_zero_is_sound ... ok [INFO] [stdout] test soundness_tests::remove_redundant_sets_is_sound ... ok [INFO] [stdout] test peephole_tests::quickcheck_should_remove_dead_pure_code ... ok [INFO] [stdout] test soundness_tests::combine_ptr_increments_is_sound ... ok [INFO] [stdout] test soundness_tests::combine_increments_is_sound ... ok [INFO] [stdout] test llvm_tests::compile_increment_with_offset has been running for over 60 seconds [INFO] [stdout] test llvm_tests::compile_multiply_move has been running for over 60 seconds [INFO] [stdout] test llvm_tests::compile_read has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "8ea88a329c8e837c9d4931cc6c92341128e437812f09c0189bc32fa66673e4ef", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8ea88a329c8e837c9d4931cc6c92341128e437812f09c0189bc32fa66673e4ef", kill_on_drop: false }` [INFO] [stdout] 8ea88a329c8e837c9d4931cc6c92341128e437812f09c0189bc32fa66673e4ef