Dec 06 11:30:45.120 INFO testing MortenLohne/rasher against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 11:30:45.120 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 11:30:45.250 INFO [stdout] 86136e23664c746a7b08b2a505e86d89d87b38fd30fbf0efdb037dcc9c07447a
Dec 06 11:30:45.252 INFO running `"docker" "start" "-a" "86136e23664c746a7b08b2a505e86d89d87b38fd30fbf0efdb037dcc9c07447a"`
Dec 06 11:30:45.591 INFO [stderr] usermod: no changes
Dec 06 11:30:45.705 INFO [stderr]    Compiling itertools v0.7.4
Dec 06 11:30:45.706 INFO [stderr]    Compiling log4rs v0.7.0
Dec 06 11:30:48.461 INFO [stderr]    Compiling rasher v0.1.0 (/source)
Dec 06 11:30:56.317 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.70s
Dec 06 11:30:56.322 INFO [stderr] su: No module specific data is present
Dec 06 11:30:56.706 INFO running `"docker" "inspect" "86136e23664c746a7b08b2a505e86d89d87b38fd30fbf0efdb037dcc9c07447a"`
Dec 06 11:30:56.790 INFO running `"docker" "rm" "-f" "86136e23664c746a7b08b2a505e86d89d87b38fd30fbf0efdb037dcc9c07447a"`
Dec 06 11:30:56.877 INFO [stdout] 86136e23664c746a7b08b2a505e86d89d87b38fd30fbf0efdb037dcc9c07447a
Dec 06 11:30:56.877 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 11:30:57.107 INFO [stdout] 8f69d04d49e3908065d0fe7d71a6b49a4535824af8970ffd743317da47b75988
Dec 06 11:30:57.108 INFO running `"docker" "start" "-a" "8f69d04d49e3908065d0fe7d71a6b49a4535824af8970ffd743317da47b75988"`
Dec 06 11:30:57.607 INFO [stderr] usermod: no changes
Dec 06 11:30:57.700 INFO [stderr]    Compiling env_logger v0.4.3
Dec 06 11:30:58.890 INFO [stderr]    Compiling quickcheck v0.5.0
Dec 06 11:31:01.332 INFO [stderr]    Compiling rasher v0.1.0 (/source)
Dec 06 11:31:09.978 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.35s
Dec 06 11:31:09.984 INFO [stderr] su: No module specific data is present
Dec 06 11:31:10.454 INFO running `"docker" "inspect" "8f69d04d49e3908065d0fe7d71a6b49a4535824af8970ffd743317da47b75988"`
Dec 06 11:31:10.743 INFO running `"docker" "rm" "-f" "8f69d04d49e3908065d0fe7d71a6b49a4535824af8970ffd743317da47b75988"`
Dec 06 11:31:11.028 INFO [stdout] 8f69d04d49e3908065d0fe7d71a6b49a4535824af8970ffd743317da47b75988
Dec 06 11:31:11.036 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 11:31:11.563 INFO [stdout] 465ca94660fc0d524dc99c782a8fea30494f548c4beaac099b71abed1cc5fb47
Dec 06 11:31:11.566 INFO running `"docker" "start" "-a" "465ca94660fc0d524dc99c782a8fea30494f548c4beaac099b71abed1cc5fb47"`
Dec 06 11:31:12.616 INFO [stderr] usermod: no changes
Dec 06 11:31:12.702 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 06 11:31:12.714 INFO [stderr]      Running /target/debug/deps/rasher-c3d851a954e9d5a6
Dec 06 11:31:12.724 INFO [stdout] 
Dec 06 11:31:12.724 INFO [stdout] running 97 tests
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::file ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::diagonals_preserve_pieces ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::antidiagonals_preserve_pieces ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::first_piece ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::from_to_iterator ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::diagonal_neighbours ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::long_antidiagonal ... ok
Dec 06 11:31:12.736 INFO [stdout] test tests::bitboard_tests::long_diagonal ... ok
Dec 06 11:31:12.741 INFO [stdout] test tests::bitboard_tests::rotate ... ok
Dec 06 11:31:12.741 INFO [stdout] test tests::bitboard_tests::pawn_attacks_both_ways ... ok
Dec 06 11:31:12.741 INFO [stdout] test tests::bitboard_tests::iterator ... ok
Dec 06 11:31:12.746 INFO [stdout] test tests::bitboard_tests::rotate_45 ... ok
Dec 06 11:31:12.746 INFO [stdout] test tests::bitboard_tests::rotate_315_back ... ok
Dec 06 11:31:12.746 INFO [stdout] test tests::bitboard_tests::rotate_45_back ... ok
Dec 06 11:31:12.753 INFO [stdout] test tests::bitboard_tests::rank ... ok
Dec 06 11:31:12.753 INFO [stdout] test tests::crazyhouse_move_gen_tests::available_moves_at_start ... ok
Dec 06 11:31:12.753 INFO [stdout] test tests::board_tests::from_alg_test ... ok
Dec 06 11:31:12.753 INFO [stdout] test tests::bitboard_tests::square_squares ... ok
Dec 06 11:31:12.753 INFO [stdout] test tests::mcts_tests::block_pawn ... ignored
Dec 06 11:31:12.753 INFO [stdout] test tests::mcts_tests::capture_to_promote ... ignored
Dec 06 11:31:12.753 INFO [stdout] test tests::mcts_tests::knight_fork_test ... ignored
Dec 06 11:31:12.753 INFO [stdout] test tests::mcts_tests::underpromote ... ignored
Dec 06 11:31:12.753 INFO [stdout] test tests::bitboard_tests::rotation_45_preserves_pieces ... ok
Dec 06 11:31:12.753 INFO [stdout] test tests::crazyhouse_move_gen_tests::block_check_with_crazyhouse_move_test ... ok
Dec 06 11:31:12.756 INFO [stdout] test tests::bitboard_tests::rotation_315_preserves_pieces ... ok
Dec 06 11:31:12.756 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test2_long ... ignored
Dec 06 11:31:12.767 INFO [stdout] test tests::move_gen_tests::capture_from_check ... ok
Dec 06 11:31:12.767 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test3_long ... ignored
Dec 06 11:31:12.772 INFO [stdout] test tests::bitboard_tests::iterator_iterates_all ... ok
Dec 06 11:31:12.772 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test4_long ... ignored
Dec 06 11:31:12.783 INFO [stdout] test tests::crazyhouse_move_gen_tests::crazyhouse_moves_test ... ok
Dec 06 11:31:12.792 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test1 ... ok
Dec 06 11:31:12.792 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test6_long ... ignored
Dec 06 11:31:13.870 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test5 ... ok
Dec 06 11:31:14.377 INFO [stdout] test tests::move_gen_tests::en_passant_test ... ok
Dec 06 11:31:14.377 INFO [stdout] test tests::move_gen_tests::is_pinned_to_piece_test ... ok
Dec 06 11:31:14.377 INFO [stdout] test tests::move_gen_tests::king_in_check_test ... ok
Dec 06 11:31:14.377 INFO [stdout] test tests::move_gen_tests::respond_to_checks ... ok
Dec 06 11:31:14.572 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test6 ... ok
Dec 06 11:31:14.572 INFO [stdout] test tests::move_gen_tests::starting_position_perf_test_long ... ignored
Dec 06 11:31:14.576 INFO [stdout] test tests::move_gen_tests::test_piece_at ... ok
Dec 06 11:31:14.608 INFO [stdout] test tests::move_gen_tests::test_square ... ok
Dec 06 11:31:14.623 INFO [stdout] test tests::score_tests::score_ordering_test ... ok
Dec 06 11:31:14.652 INFO [stdout] test tests::sjadam_board_tests::hash_stays_equal ... ok
Dec 06 11:31:14.660 INFO [stdout] test tests::sjadam_board_tests::pawn_moves_can_repeat ... ok
Dec 06 11:31:14.660 INFO [stdout] test tests::sjadam_board_tests::repetitions_are_drawn ... ok
Dec 06 11:31:14.660 INFO [stdout] test tests::sjadam_board_tests::repetitions_do_not_preserve_hash ... ok
Dec 06 11:31:14.660 INFO [stdout] test tests::sjadam_move_gen_tests::bishop_moves ... ok
Dec 06 11:31:14.660 INFO [stdout] test tests::sjadam_move_gen_tests::can_take_king_while_checked ... ok
Dec 06 11:31:15.800 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test7 ... ok
Dec 06 11:31:16.771 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test2 ... ok
Dec 06 11:31:16.771 INFO [stdout] test tests::sjadam_move_gen_tests::correct_move_gen_start_pos ... ok
Dec 06 11:31:16.771 INFO [stdout] test tests::sjadam_move_gen_tests::jump_over_opponent ... ok
Dec 06 11:31:16.771 INFO [stdout] test tests::sjadam_move_gen_tests::no_moves_on_back_rank ... ok
Dec 06 11:31:16.771 INFO [stdout] test tests::sjadam_move_gen_tests::no_stalemate_test ... ok
Dec 06 11:31:17.796 INFO [stdout] test tests::move_gen_tests::castling_test ... ok
Dec 06 11:31:18.000 INFO [stdout] test tests::move_gen_tests::starting_position_perf_test ... ok
Dec 06 11:31:22.160 INFO [stdout] test tests::crazyhouse_move_gen_tests::starting_position_perf_test ... ok
Dec 06 11:31:28.216 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test4 ... ok
Dec 06 11:31:49.008 INFO [stdout] test tests::move_gen_tests::correct_move_gen_test3 ... ok
Dec 06 11:32:14.661 INFO [stdout] test tests::sjadam_move_gen_tests::castling_en_passant_perf_test ... test tests::sjadam_move_gen_tests::castling_en_passant_perf_test has been running for over 60 seconds
Dec 06 11:32:15.808 INFO [stdout] test tests::sjadam_move_gen_tests::castling_en_passant_perf_test_2 ... test tests::sjadam_move_gen_tests::castling_en_passant_perf_test_2 has been running for over 60 seconds
Dec 06 11:32:16.772 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_3 ... test tests::sjadam_move_gen_tests::perf_test_3 has been running for over 60 seconds
Dec 06 11:32:17.800 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_4 ... test tests::sjadam_move_gen_tests::perf_test_4 has been running for over 60 seconds
Dec 06 11:32:18.008 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_5 ... test tests::sjadam_move_gen_tests::perf_test_5 has been running for over 60 seconds
Dec 06 11:32:22.180 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_6 ... test tests::sjadam_move_gen_tests::perf_test_6 has been running for over 60 seconds
Dec 06 11:32:28.220 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_7 ... test tests::sjadam_move_gen_tests::perf_test_7 has been running for over 60 seconds
Dec 06 11:32:49.007 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_8 ... test tests::sjadam_move_gen_tests::perf_test_8 has been running for over 60 seconds
Dec 06 11:33:03.612 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_4 ... ok
Dec 06 11:33:03.616 INFO [stdout] test tests::sjadam_move_gen_tests::possible_sjadam_squares ... ok
Dec 06 11:33:03.620 INFO [stdout] test tests::sjadam_move_gen_tests::sjadam_move_to_promote_pawn ... ok
Dec 06 11:33:08.748 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_3 ... ok
Dec 06 11:33:08.748 INFO [stdout] test tests::sjadam_move_gen_tests::startpos_perf_test_long ... ignored
Dec 06 11:33:08.792 INFO [stdout] test tests::sjadam_tactics_tests::can_move_in_draw_position ... ok
Dec 06 11:33:08.996 INFO [stdout] test tests::sjadam_tactics_tests::promote_pawn_to_mate ... ok
Dec 06 11:33:19.368 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_6 ... ok
Dec 06 11:33:19.960 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate1 ... ok
Dec 06 11:33:20.504 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_7 ... ok
Dec 06 11:33:21.144 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate11 ... ok
Dec 06 11:33:21.165 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate10 ... ok
Dec 06 11:33:24.308 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate13 ... ok
Dec 06 11:33:24.615 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate12 ... ok
Dec 06 11:33:26.376 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate14 ... ok
Dec 06 11:33:27.928 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate16 ... ok
Dec 06 11:33:28.924 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate17 ... ok
Dec 06 11:33:31.368 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate15 ... ok
Dec 06 11:33:32.024 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate2 ... ok
Dec 06 11:33:32.304 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate18 ... ok
Dec 06 11:33:32.948 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate6 ... ok
Dec 06 11:33:34.304 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate7 ... ok
Dec 06 11:33:35.608 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate8 ... ok
Dec 06 11:33:38.304 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate9 ... ok
Dec 06 11:33:38.584 INFO [stdout] test tests::sjadam_tactics_tests::sjadammate3 ... ok
Dec 06 11:33:44.340 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_5 ... ok
Dec 06 11:33:54.153 INFO [stdout] test tests::tactics_tests::basic_tactics_test ... ok
Dec 06 11:33:54.692 INFO [stdout] test tests::sjadam_move_gen_tests::perf_test_8 ... ok
Dec 06 11:33:54.692 INFO [stdout] test tests::tactics_tests::multipv_mates_test_long ... ignored
Dec 06 11:33:54.693 INFO [stdout] test tests::uci_tests::parse_go_test ... ok
Dec 06 11:33:57.945 INFO [stdout] test tests::sjadam_move_gen_tests::castling_en_passant_perf_test ... ok
Dec 06 11:34:03.624 INFO [stdout] test tests::sjadam_move_gen_tests::startpos_perf_test ... test tests::sjadam_move_gen_tests::startpos_perf_test has been running for over 60 seconds
Dec 06 11:34:07.821 INFO [stdout] test tests::tactics_tests::multipv_mates_test ... ok
Dec 06 11:34:08.990 INFO [stdout] test tests::sjadam_tactics_tests::repetitions_score_0 ... test tests::sjadam_tactics_tests::repetitions_score_0 has been running for over 60 seconds
Dec 06 11:34:10.878 INFO [stdout] test tests::tactics_tests::multipv_mates_test2 ... ok
Dec 06 11:34:28.789 INFO [stdout] test tests::tactics_tests::mate_in_two_test ... ok
Dec 06 11:34:30.220 INFO [stdout] test tests::sjadam_move_gen_tests::castling_en_passant_perf_test_2 ... ok
Dec 06 11:34:36.566 INFO [stdout] test tests::sjadam_move_gen_tests::startpos_perf_test ... ok
Dec 06 11:34:56.249 INFO [stdout] test tests::sjadam_tactics_tests::repetitions_score_0 ... ok
Dec 06 11:34:56.249 INFO [stdout] 
Dec 06 11:34:56.249 INFO [stdout] test result: ok. 86 passed; 0 failed; 11 ignored; 0 measured; 0 filtered out
Dec 06 11:34:56.249 INFO [stdout] 
Dec 06 11:34:56.252 INFO [stderr] su: No module specific data is present
Dec 06 11:34:56.714 INFO running `"docker" "inspect" "465ca94660fc0d524dc99c782a8fea30494f548c4beaac099b71abed1cc5fb47"`
Dec 06 11:34:56.813 INFO running `"docker" "rm" "-f" "465ca94660fc0d524dc99c782a8fea30494f548c4beaac099b71abed1cc5fb47"`
Dec 06 11:34:56.908 INFO [stdout] 465ca94660fc0d524dc99c782a8fea30494f548c4beaac099b71abed1cc5fb47