[INFO] cloning repository tuzz/supermutation
[INFO] running `"git" "clone" "--bare" "git://github.com/tuzz/supermutation.git" "work/cache/sources/gh/tuzz/supermutation"`
[INFO] [stderr] Cloning into bare repository 'work/cache/sources/gh/tuzz/supermutation'...
[INFO] running `"git" "clone" "work/cache/sources/gh/tuzz/supermutation" "work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/gh/tuzz/supermutation"`
[INFO] [stderr] Cloning into 'work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/gh/tuzz/supermutation'...
[INFO] [stderr] done.
[INFO] running `"git" "clone" "work/cache/sources/gh/tuzz/supermutation" "work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/tuzz/supermutation"`
[INFO] [stderr] Cloning into 'work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/tuzz/supermutation'...
[INFO] [stderr] done.
[INFO] running `"git" "rev-parse" "HEAD"`
[INFO] [stdout] eafbd87c5cb2e3c1926035910f6f21323a976f03
[INFO] sha for GitHub repo tuzz/supermutation: eafbd87c5cb2e3c1926035910f6f21323a976f03
[INFO] validating manifest of tuzz/supermutation on toolchain master#c8865d8e195813ade6b84434ac9f8850e7112d1a
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of tuzz/supermutation on toolchain try#e212b7d492c96d3837691ba29d32d5f468933fb8
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing tuzz/supermutation
[INFO] finished frobbing tuzz/supermutation
[INFO] frobbed toml for tuzz/supermutation written to work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/gh/tuzz/supermutation/Cargo.toml
[INFO] started frobbing tuzz/supermutation
[INFO] finished frobbing tuzz/supermutation
[INFO] frobbed toml for tuzz/supermutation written to work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/tuzz/supermutation/Cargo.toml
[INFO] crate tuzz/supermutation already has a lockfile, it will not be regenerated
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded croaring v0.3.7
[INFO] [stderr]   Downloaded lehmer v3.0.0
[INFO] [stderr]   Downloaded croaring-sys v0.3.7
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing tuzz/supermutation against try#e212b7d492c96d3837691ba29d32d5f468933fb8 for pr-61629
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-0/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/tuzz/supermutation:/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=forbid" "-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" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "build" "--frozen"`
[INFO] [stdout] 42b5541adfac81021b2551eb11fadf5c7758b28eb92f1814f7b2622720ab2726
[INFO] running `"docker" "start" "-a" "42b5541adfac81021b2551eb11fadf5c7758b28eb92f1814f7b2622720ab2726"`
[INFO] [stderr]    Compiling regex v1.0.6
[INFO] [stderr]    Compiling bindgen v0.37.4
[INFO] [stderr]    Compiling bucket_queue v2.0.0
[INFO] [stderr]    Compiling lehmer v3.0.0
[INFO] [stderr]    Compiling clang-sys v0.23.0
[INFO] [stderr]    Compiling which v1.0.5
[INFO] [stderr]    Compiling nom v3.2.1
[INFO] [stderr]    Compiling cexpr v0.2.3
[INFO] [stderr]    Compiling env_logger v0.5.13
[INFO] [stderr]    Compiling croaring-sys v0.3.7
[INFO] [stderr]    Compiling croaring v0.3.7
[INFO] [stderr]    Compiling supermutation v0.1.0 (/opt/crater/workdir)
[INFO] [stderr] warning: method is never used: `len`
[INFO] [stderr]   --> src/closed_set/mod.rs:24:5
[INFO] [stderr]    |
[INFO] [stderr] 24 |     pub fn len(&self) -> usize {
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: #[warn(dead_code)] on by default
[INFO] [stderr] 
[INFO] [stderr] warning: method is never used: `len`
[INFO] [stderr]   --> src/open_set/mod.rs:28:5
[INFO] [stderr]    |
[INFO] [stderr] 28 |     pub fn len(&self) -> usize {
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: method is never used: `maximum_f_cost`
[INFO] [stderr]   --> src/open_set/mod.rs:36:5
[INFO] [stderr]    |
[INFO] [stderr] 36 |     pub fn maximum_f_cost(&self) -> Option<usize> {
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: method is never used: `open_set_len`
[INFO] [stderr]   --> src/search/mod.rs:62:5
[INFO] [stderr]    |
[INFO] [stderr] 62 |     pub fn open_set_len(&self) -> usize {
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: method is never used: `closed_set_len`
[INFO] [stderr]   --> src/search/mod.rs:66:5
[INFO] [stderr]    |
[INFO] [stderr] 66 |     pub fn closed_set_len(&self) -> usize {
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: the feature `uniform_paths` has been stable since 1.32.0 and no longer requires an attribute to enable
[INFO] [stderr]  --> src/main.rs:1:12
[INFO] [stderr]   |
[INFO] [stderr] 1 | #![feature(uniform_paths)]
[INFO] [stderr]   |            ^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(stable_features)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 26s
[INFO] running `"docker" "inspect" "42b5541adfac81021b2551eb11fadf5c7758b28eb92f1814f7b2622720ab2726"`
[INFO] running `"docker" "rm" "-f" "42b5541adfac81021b2551eb11fadf5c7758b28eb92f1814f7b2622720ab2726"`
[INFO] [stdout] 42b5541adfac81021b2551eb11fadf5c7758b28eb92f1814f7b2622720ab2726
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-0/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/tuzz/supermutation:/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=forbid" "-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" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] c40ea752e29184905b6e1277a38cd6ab87b55e8145edea50c0219eb672832032
[INFO] running `"docker" "start" "-a" "c40ea752e29184905b6e1277a38cd6ab87b55e8145edea50c0219eb672832032"`
[INFO] [stderr]    Compiling supermutation v0.1.0 (/opt/crater/workdir)
[INFO] [stderr] warning: the feature `uniform_paths` has been stable since 1.32.0 and no longer requires an attribute to enable
[INFO] [stderr]  --> src/main.rs:1:12
[INFO] [stderr]   |
[INFO] [stderr] 1 | #![feature(uniform_paths)]
[INFO] [stderr]   |            ^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(stable_features)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.60s
[INFO] running `"docker" "inspect" "c40ea752e29184905b6e1277a38cd6ab87b55e8145edea50c0219eb672832032"`
[INFO] running `"docker" "rm" "-f" "c40ea752e29184905b6e1277a38cd6ab87b55e8145edea50c0219eb672832032"`
[INFO] [stdout] c40ea752e29184905b6e1277a38cd6ab87b55e8145edea50c0219eb672832032
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-0/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/tuzz/supermutation:/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=forbid" "-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" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "test" "--frozen"`
[INFO] [stdout] 89439e4b2e47a2a81f1062886f90f688e068888cfc65c893e2d666a7d7a3e7e9
[INFO] running `"docker" "start" "-a" "89439e4b2e47a2a81f1062886f90f688e068888cfc65c893e2d666a7d7a3e7e9"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/supermutation-c5d60a2ecf06b722
[INFO] [stdout] 
[INFO] [stdout] running 62 tests
[INFO] [stdout] test candidate::test::expand::it_maps_the_permutation_to_its_canonical_form ... ok
[INFO] [stdout] test candidate::test::maximum_permutations::it_returns_the_factorial_of_the_number_of_symbols ... ok
[INFO] [stdout] test candidate::test::expand::it_adds_a_new_permutation_when_the_zero_symbol_is_expanded ... ok
[INFO] [stdout] test candidate::test::expand::it_adds_removed_counter_bits_based_on_the_expanded_symbol ... ok
[INFO] [stdout] test candidate::test::expand::it_sets_counter_bits_based_on_the_expanded_symbol ... ok
[INFO] [stdout] test candidate::test::expand::it_incrementally_adds_new_permutations ... ok
[INFO] [stdout] test candidate::test::ord::it_consistently_orders_candidates ... ok
[INFO] [stdout] test candidate::test::number_of_bits::it_returns_the_number_of_bits_that_are_set_in_the_bitmap ... ok
[INFO] [stdout] test candidate::test::number_of_permutations::it_returns_the_number_of_permutations_that_have_been_seen ... ok
[INFO] [stdout] test candidate::test::seed::it_has_seen_the_first_permutation ... ok
[INFO] [stdout] test candidate::test::seed::it_sets_all_the_counter_bits ... ok
[INFO] [stdout] test closed_set::test::add::it_adds_a_candidate_to_the_closed_set_with_its_g_cost ... ok
[INFO] [stdout] test closed_set::test::contains::it_returns_true_if_the_closed_set_contains_the_candidate ... ok
[INFO] [stdout] test closed_set::test::len::it_returns_the_number_of_candidates_in_the_closed_set ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_adds_a_new_lower_bound_for_the_next_goal ... ok
[INFO] [stdout] test candidate::test::ord::it_orders_candidates_with_fewer_bits_first ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_adds_a_new_max_depth_for_the_next_goal ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_adds_the_full_shortest_path_distance_for_indexes_below_starting_perms ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_adds_the_new_distance_to_its_shortest_path_distances ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_increases_the_lower_bound_of_the_next_goal_based_on_previous_knowledge ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_keeps_a_max_depth_of_zero_for_indexes_below_starting_perms ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_sets_lower_bound_to_the_nearest_distance_to_the_end_for_each_number_of_perms ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_sets_max_depth_to_the_furthest_distance_from_the_start_for_each_number_of_perms ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_sets_the_new_lower_bound_to_zero_for_the_next_goal ... ok
[INFO] [stdout] test heuristic::test::improve_based_on::it_sets_the_new_max_depth_to_zero_for_the_next_goal ... ok
[INFO] [stdout] test heuristic::test::next_goal::it_returns_the_number_of_perms_in_the_next_subgoal ... ok
[INFO] [stdout] test heuristic::test::seed::it_sets_lower_bounds_to_descending_distances ... ok
[INFO] [stdout] test heuristic::test::seed::it_sets_max_depth_elements_to_zero ... ok
[INFO] [stdout] test heuristic::test::seed::it_sets_starting_perms_from_the_seed_candidate ... ok
[INFO] [stdout] test heuristic::test::seed::it_sets_the_length_of_lower_bounds_so_that_the_next_goal_can_be_used_as_an_index ... ok
[INFO] [stdout] test heuristic::test::seed::it_sets_the_length_of_max_depth_so_that_the_next_goal_can_be_used_as_an_index ... ok
[INFO] [stdout] test interface::test::ask_for_symbols::it_returns_five_symbols_in_the_test_environment ... ok
[INFO] [stdout] test heuristic::test::cost::it_returns_the_cumulative_distance_for_candidates_with_less_than_starting_perms ... ok
[INFO] [stdout] test heuristic::test::cost::it_returns_the_shortest_distance_for_candidates_on_the_optimal_path ... ok
[INFO] [stdout] test heuristic::test::cost::it_returns_zero_distance_if_already_at_the_goal ... ok
[INFO] [stdout] test open_set::test::add::it_adds_the_candidate_and_sets_their_f_cost ... ok
[INFO] [stdout] test heuristic::test::first_goal::it_returns_the_number_of_perms_in_the_first_subgoal ... ok
[INFO] [stdout] test heuristic::test::cost::it_returns_the_shortest_distance_for_candidates_off_the_optimal_path ... ok
[INFO] [stdout] test closed_set::test::contains::it_returns_false_if_it_contains_the_candidate_with_a_higher_g_cost ... ok
[INFO] [stdout] test open_set::test::add::it_adds_the_candidate_and_sets_their_g_cost ... ok
[INFO] [stdout] test open_set::test::maximum_f_cost::it_returns_the_maximum_f_cost_in_the_open_set ... ok
[INFO] [stdout] test open_set::test::len::it_returns_the_number_of_candidates_in_the_open_set ... ok
[INFO] [stdout] test open_set::test::minimum_f_cost::it_returns_the_minimum_f_cost_in_the_open_set ... ok
[INFO] [stdout] test open_set::test::next::when_candidates_have_the_same_f_cost::it_returns_the_candidates_ordered_by_g_cost_descending ... ok
[INFO] [stdout] test search::test::closed_set_len::it_returns_the_number_of_candidates_in_the_closed_set ... ok
[INFO] [stdout] test open_set::test::new::it_builds_an_empty_open_set ... ok
[INFO] [stdout] test open_set::test::next::it_returns_the_candidates_ordered_by_f_cost_ascending ... ok
[INFO] [stdout] test search::test::open_set_len::it_returns_the_number_of_candidates_in_the_open_set ... ok
[INFO] [stdout] test search::test::shortest_path::it_reuses_the_open_and_closed_sets_in_between_searches ... ok
[INFO] [stdout] test search::test::update_heuristic::it_does_not_change_the_g_costs_for_nested_buckets ... ok
[INFO] [stdout] test search::test::update_heuristic::it_appends_buckets ... ok
[INFO] [stdout] test search::test::update_heuristic::it_sets_the_new_heuristic_for_the_search ... ok
[INFO] [stdout] test symmetry::test::counter_mappings::it_returns_mappings_to_update_the_counter_bits_at_the_end_of_the_bitmap ... ok
[INFO] [stdout] test symmetry::test::permutation_mappings::it_returns_the_index_mappings_to_transpose_each_permutation ... ok
[INFO] [stdout] test search::test::update_heuristic::it_recalculates_the_open_set_costs ... ok
[INFO] [stdout] test symmetry::test::transpositions::it_returns_the_transposed_permutations_for_all_possible_string_expansions ... ok
[INFO] [stdout] test utility::test::factorial::it_calculates_the_factorial_of_a_number ... ok
[INFO] [stdout] test search::test::shortest_path::it_calculates_the_correct_costs_for_the_expanded_candidates ... ok
[INFO] [stdout] test search::test::seed::it_adds_the_candidate_to_the_open_set ... ok
[INFO] [stdout] test symmetry::test::mapping::it_chooses_the_mapping_resulting_in_the_bitset_with_leftmost_bits_set ... ok
[INFO] [stdout] test search::test::update_heuristic::it_does_not_reorder_candidates_in_buckets_with_the_same_g_cost ... ok
[INFO] [stdout] test search::test::shortest_path::it_finds_the_length_of_the_shortest_path_to_the_goal_number_of_permutations ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "89439e4b2e47a2a81f1062886f90f688e068888cfc65c893e2d666a7d7a3e7e9"`
[INFO] running `"docker" "rm" "-f" "89439e4b2e47a2a81f1062886f90f688e068888cfc65c893e2d666a7d7a3e7e9"`
[INFO] [stdout] 89439e4b2e47a2a81f1062886f90f688e068888cfc65c893e2d666a7d7a3e7e9