[INFO] crate bfc 1.7.0 is already in cache
[INFO] extracting crate bfc 1.7.0 into work/ex/beta-1.37-6/sources/1.36.0/reg/bfc/1.7.0
[INFO] extracting crate bfc 1.7.0 into work/ex/beta-1.37-6/sources/beta-2019-07-23/reg/bfc/1.7.0
[INFO] validating manifest of bfc-1.7.0 on toolchain 1.36.0
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.36.0" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of bfc-1.7.0 on toolchain beta-2019-07-23
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-07-23" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing bfc-1.7.0
[INFO] finished frobbing bfc-1.7.0
[INFO] frobbed toml for bfc-1.7.0 written to work/ex/beta-1.37-6/sources/1.36.0/reg/bfc/1.7.0/Cargo.toml
[INFO] started frobbing bfc-1.7.0
[INFO] finished frobbing bfc-1.7.0
[INFO] frobbed toml for bfc-1.7.0 written to work/ex/beta-1.37-6/sources/beta-2019-07-23/reg/bfc/1.7.0/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.36.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-07-23" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.36.0" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-07-23" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing bfc-1.7.0 against beta-2019-07-23 for beta-1.37-6
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.37-6/worker-6/beta-2019-07-23:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.37-6/sources/beta-2019-07-23/reg/bfc/1.7.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+beta-2019-07-23" "build" "--frozen"`
[INFO] [stdout] 05a228b16a68db6f59d79bab1bb7d812e27d31cfda887a3cddeb3e0e7cd8d716
[INFO] running `"docker" "start" "-a" "05a228b16a68db6f59d79bab1bb7d812e27d31cfda887a3cddeb3e0e7cd8d716"`
[INFO] [stderr]    Compiling semver v0.1.20
[INFO] [stderr]    Compiling bitflags v0.5.0
[INFO] [stderr]    Compiling quickcheck v0.3.2
[INFO] [stderr]    Compiling llvm-sys v0.3.0
[INFO] [stderr]    Compiling bfc v1.7.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.74s
[INFO] running `"docker" "inspect" "05a228b16a68db6f59d79bab1bb7d812e27d31cfda887a3cddeb3e0e7cd8d716"`
[INFO] running `"docker" "rm" "-f" "05a228b16a68db6f59d79bab1bb7d812e27d31cfda887a3cddeb3e0e7cd8d716"`
[INFO] [stdout] 05a228b16a68db6f59d79bab1bb7d812e27d31cfda887a3cddeb3e0e7cd8d716
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.37-6/worker-6/beta-2019-07-23:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.37-6/sources/beta-2019-07-23/reg/bfc/1.7.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+beta-2019-07-23" "test" "--frozen" "--no-run"`
[INFO] [stdout] 66dda9e559bb352b59755d566d2094f4a532a16d5fd5fcdc948ba58f73e35401
[INFO] running `"docker" "start" "-a" "66dda9e559bb352b59755d566d2094f4a532a16d5fd5fcdc948ba58f73e35401"`
[INFO] [stderr]    Compiling bfc v1.7.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.24s
[INFO] running `"docker" "inspect" "66dda9e559bb352b59755d566d2094f4a532a16d5fd5fcdc948ba58f73e35401"`
[INFO] running `"docker" "rm" "-f" "66dda9e559bb352b59755d566d2094f4a532a16d5fd5fcdc948ba58f73e35401"`
[INFO] [stdout] 66dda9e559bb352b59755d566d2094f4a532a16d5fd5fcdc948ba58f73e35401
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.37-6/worker-6/beta-2019-07-23:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.37-6/sources/beta-2019-07-23/reg/bfc/1.7.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+beta-2019-07-23" "test" "--frozen"`
[INFO] [stdout] ad74fd1e21457bd068098833d3fbe587cf305157bfe40282b6552f822a225eff
[INFO] running `"docker" "start" "-a" "ad74fd1e21457bd068098833d3fbe587cf305157bfe40282b6552f822a225eff"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/bfc-d7011dbb9d22668f
[INFO] [stdout] 
[INFO] [stdout] running 158 tests
[INFO] [stdout] test bfir::parse_decrement ... ok
[INFO] [stdout] test bfir::parse_empty_loop ... ok
[INFO] [stdout] test bfir::parse_complex_loop ... ok
[INFO] [stdout] test bfir::parse_pointer_decrement ... ok
[INFO] [stdout] test bfir::parse_comment ... ok
[INFO] [stdout] test bfir::parse_simple_loop ... ok
[INFO] [stdout] test bfir::parse_increment ... ok
[INFO] [stdout] test bfir::parse_pointer_increment ... ok
[INFO] [stdout] test bfir::parse_read ... 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 bfir::parse_unbalanced_loop ... ok
[INFO] [stdout] test bfir::parse_write ... ok
[INFO] [stdout] test bounds::increment_offset_bounds ... ok
[INFO] [stdout] test bounds::excessive_bounds_truncated ... ok
[INFO] [stdout] test bounds::multiple_ptr_increment_bounds ... ok
[INFO] [stdout] test bounds::loop_with_no_net_movement ... ok
[INFO] [stdout] test bounds::multiply_move_bounds ... ok
[INFO] [stdout] test bounds::multiply_move_backwards_bounds ... ok
[INFO] [stdout] test executable_name_b ... ok
[INFO] [stdout] test executable_name_bf ... ok
[INFO] [stdout] test executable_name_relative_path ... ok
[INFO] [stdout] test bounds::set_offset_bounds ... ok
[INFO] [stdout] test bounds::ptr_increment_sequence_bounds ... ok
[INFO] [stdout] test bounds::unbounded_movement ... ok
[INFO] [stdout] test bounds::ptr_increment_bounds ... ok
[INFO] [stdout] test bounds::one_cell_bounds ... ok
[INFO] [stdout] test bounds::multiply_move_bounds_are_relative ... ok
[INFO] [stdout] test execution::cant_evaluate_inputs ... ok
[INFO] [stdout] test execution::execute_read_with_dummy_value ... ok
[INFO] [stdout] test execution::increment_executed ... ok
[INFO] [stdout] test execution::increment_wraps ... ok
[INFO] [stdout] test execution::decrement_executed ... ok
[INFO] [stdout] test execution::execute_read_with_dummy_value_nested_loop ... ok
[INFO] [stdout] test execution::limit_to_steps_specified ... ok
[INFO] [stdout] test execution::loop_with_read_body ... ok
[INFO] [stdout] test execution::multiply_move_executed ... ok
[INFO] [stdout] test execution::loop_up_to_step_limit ... ok
[INFO] [stdout] test execution::loop_executed ... ok
[INFO] [stdout] test execution::multiply_move_offset_too_low ... ok
[INFO] [stdout] test execution::multiply_move_when_current_cell_is_zero ... ok
[INFO] [stdout] test execution::partially_execute_complete_toplevel_loop ... ok
[INFO] [stdout] test execution::ptr_out_of_range ... ok
[INFO] [stdout] test execution::partially_execute_up_to_step_limit ... ok
[INFO] [stdout] test execution::ptr_increment_executed ... ok
[INFO] [stdout] test execution::partially_execute_up_to_runtime_value ... ok
[INFO] [stdout] test execution::set_wraps ... ok
[INFO] [stdout] test execution::up_to_infinite_loop_executed ... ok
[INFO] [stdout] test execution::set_executed ... ok
[INFO] [stdout] test execution::multiply_move_wrapping ... ok
[INFO] [stdout] test execution::up_to_nonempty_infinite_loop ... ok
[INFO] [stdout] test execution::write_executed ... ok
[INFO] [stdout] test llvm_tests::compile_empty_program ... ok
[INFO] [stdout] test llvm_tests::compile_increment ... ok
[INFO] [stdout] test execution::arithmetic_error_nested_loops ... ok
[INFO] [stdout] test llvm_tests::compile_ptr_increment ... ok
[INFO] [stdout] test llvm_tests::compile_increment_with_offset ... ok
[INFO] [stdout] test llvm_tests::compile_loop ... ok
[INFO] [stdout] test llvm_tests::compile_set_with_offset ... ok
[INFO] [stdout] test llvm_tests::compile_start_instr_midway ... ok
[INFO] [stdout] test llvm_tests::compile_static_outputs ... ok
[INFO] [stdout] test llvm_tests::compile_write ... ok
[INFO] [stdout] test llvm_tests::respect_initial_cell_ptr ... ok
[INFO] [stdout] test llvm_tests::compile_read ... ok
[INFO] [stdout] test llvm_tests::compile_multiply_move ... ok
[INFO] [stdout] test peephole_tests::combine_increment_sum_to_zero ... ok
[INFO] [stdout] test peephole_tests::combine_increments_after_sort ... ok
[INFO] [stdout] test peephole_tests::combine_increments_nested ... ok
[INFO] [stdout] test peephole_tests::combine_increments_flat ... ok
[INFO] [stdout] test peephole_tests::combine_before_read_not_consecutive ... ok
[INFO] [stdout] test llvm_tests::set_initial_cell_values ... ok
[INFO] [stdout] test peephole_tests::combine_increments_unrelated ... ok
[INFO] [stdout] test bounds::quickcheck_highest_cell_index_in_bounds ... ok
[INFO] [stdout] test peephole_tests::dont_combine_before_read_different_offset ... ok
[INFO] [stdout] test peephole_tests::combine_set_and_increment_non_adjacent_instrs ... ok
[INFO] [stdout] test peephole_tests::combine_set_sum_to_zero ... ok
[INFO] [stdout] test peephole_tests::combine_increments_non_adjacent_instrs ... ok
[INFO] [stdout] test peephole_tests::next_mutate_increment ... ok
[INFO] [stdout] test peephole_tests::combine_increments_remove_redundant ... ok
[INFO] [stdout] test peephole_tests::next_mutate_consider_pointer_increment ... ok
[INFO] [stdout] test peephole_tests::dont_simplify_multiple_decrement_loop ... ok
[INFO] [stdout] test peephole_tests::next_mutate_loop ... ok
[INFO] [stdout] test peephole_tests::no_combine_before_read_after_write ... ok
[INFO] [stdout] test peephole_tests::no_combine_before_read_after_multiply ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_ignore_write ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_increment ... ok
[INFO] [stdout] test peephole_tests::not_redundant_set_when_nonzero ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_loop ... ok
[INFO] [stdout] test peephole_tests::pathological_optimisation_opportunity ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_ignores_offset_at_index ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_multiply_offset_doesnt_match ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_no_predecessors ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_multiply_ignore_offset ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_increment_matching_offset ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_consider_pointer_increment ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_multiply_offset_matches ... ok
[INFO] [stdout] test peephole_tests::prev_mutate_set ... ok
[INFO] [stdout] test execution::multiply_move_offset_too_high ... ok
[INFO] [stdout] test peephole_tests::quickcheck_combine_increments_remove_zero_any_offset ... ok
[INFO] [stdout] test peephole_tests::quickcheck_combine_increment_and_set_different_offsets ... ok
[INFO] [stdout] test peephole_tests::quickcheck_combine_set_and_set ... ok
[INFO] [stdout] test peephole_tests::quickcheck_combine_set_and_set_different_offsets ... ok
[INFO] [stdout] test peephole_tests::quickcheck_combine_set_and_increment_different_offsets ... 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_set ... 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::remove_repeated_loops ... ok
[INFO] [stdout] test peephole_tests::should_annotate_known_zero ... ok
[INFO] [stdout] test peephole_tests::should_annotate_known_zero_cleaned_up ... ok
[INFO] [stdout] test peephole_tests::quickcheck_sort_by_offset_pointer_increments ... ok
[INFO] [stdout] test peephole_tests::should_annotate_known_zero_nested ... 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_combine_set_and_set_nested ... ok
[INFO] [stdout] test peephole_tests::should_extract_multiply_multiple_cells ... ok
[INFO] [stdout] test peephole_tests::should_extract_multiply_negative_number ... ok
[INFO] [stdout] test peephole_tests::should_extract_multiply_nested ... ok
[INFO] [stdout] test peephole_tests::should_extract_multiply_simple ... ok
[INFO] [stdout] test peephole_tests::should_not_extract_multiply_from_clear_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_without_decrement ... ok
[INFO] [stdout] test peephole_tests::should_preserve_set_0_in_loop ... ok
[INFO] [stdout] test peephole_tests::should_remove_pure_code ... ok
[INFO] [stdout] test peephole_tests::should_remove_redundant_set ... ok
[INFO] [stdout] test peephole_tests::should_remove_redundant_set_multiply ... ok
[INFO] [stdout] test peephole_tests::simplify_nested_zeroing_loop ... 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_increment_nested ... 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::should_combine_ptr_increments ... 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 peephole_tests::quickcheck_optimize_should_be_idempotent ... ok
[INFO] [stdout] test execution::quickcheck_cell_ptr_in_bounds ... 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_before_read_is_sound ... ok
[INFO] [stdout] test soundness_tests::combine_increments_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::extract_multiply_is_sound ... ok
[INFO] [stdout] test soundness_tests::remove_pure_code_is_sound ... ok
[INFO] [stdout] test soundness_tests::sort_by_offset_is_sound ... ok
[INFO] [stdout] test soundness_tests::remove_redundant_sets_is_sound ... ok
[INFO] [stdout] test soundness_tests::simplify_loops_is_sound ... ok
[INFO] [stdout] test soundness_tests::test_overall_optimize_is_sound ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 158 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "ad74fd1e21457bd068098833d3fbe587cf305157bfe40282b6552f822a225eff"`
[INFO] running `"docker" "rm" "-f" "ad74fd1e21457bd068098833d3fbe587cf305157bfe40282b6552f822a225eff"`
[INFO] [stdout] ad74fd1e21457bd068098833d3fbe587cf305157bfe40282b6552f822a225eff