[INFO] cloning repository https://github.com/kz04px/faeries
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/kz04px/faeries" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkz04px%2Ffaeries", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkz04px%2Ffaeries'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] b6b74f37c59101c1bb57e37e86f14994a8e5fd9a
[INFO] testing kz04px/faeries against 1.90.0 for beta-1.91-3
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkz04px%2Ffaeries" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/kz04px/faeries
[INFO] finished tweaking git repo https://github.com/kz04px/faeries
[INFO] tweaked toml for git repo https://github.com/kz04px/faeries written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/kz04px/faeries on toolchain 1.90.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/kz04px/faeries already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 666a5aeb2d53e39aed5f1f2f3272afdd09e6ca4b3decd76a47a052f47fba89b5
[INFO] running `Command { std: "docker" "start" "-a" "666a5aeb2d53e39aed5f1f2f3272afdd09e6ca4b3decd76a47a052f47fba89b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "666a5aeb2d53e39aed5f1f2f3272afdd09e6ca4b3decd76a47a052f47fba89b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "666a5aeb2d53e39aed5f1f2f3272afdd09e6ca4b3decd76a47a052f47fba89b5", kill_on_drop: false }`
[INFO] [stdout] 666a5aeb2d53e39aed5f1f2f3272afdd09e6ca4b3decd76a47a052f47fba89b5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c423a65cbde7d0381ebc91d2593adc527e47b5d934fc32ec567a44e8cd20a09e
[INFO] running `Command { std: "docker" "start" "-a" "c423a65cbde7d0381ebc91d2593adc527e47b5d934fc32ec567a44e8cd20a09e", kill_on_drop: false }`
[INFO] [stderr]    Compiling games v0.1.0 (/opt/rustwide/workdir/games)
[INFO] [stderr]    Compiling protocols v0.1.0 (/opt/rustwide/workdir/protocols)
[INFO] [stderr]    Compiling faeries v0.1.0 (/opt/rustwide/workdir/faeries)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.52s
[INFO] running `Command { std: "docker" "inspect" "c423a65cbde7d0381ebc91d2593adc527e47b5d934fc32ec567a44e8cd20a09e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c423a65cbde7d0381ebc91d2593adc527e47b5d934fc32ec567a44e8cd20a09e", kill_on_drop: false }`
[INFO] [stdout] c423a65cbde7d0381ebc91d2593adc527e47b5d934fc32ec567a44e8cd20a09e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ac76912daa5f21039b01e6498a8dfe85b883e1f56e3308b35126067df3e33d08
[INFO] running `Command { std: "docker" "start" "-a" "ac76912daa5f21039b01e6498a8dfe85b883e1f56e3308b35126067df3e33d08", kill_on_drop: false }`
[INFO] [stderr]    Compiling games v0.1.0 (/opt/rustwide/workdir/games)
[INFO] [stderr]    Compiling faeries v0.1.0 (/opt/rustwide/workdir/faeries)
[INFO] [stderr]    Compiling protocols v0.1.0 (/opt/rustwide/workdir/protocols)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.09s
[INFO] running `Command { std: "docker" "inspect" "ac76912daa5f21039b01e6498a8dfe85b883e1f56e3308b35126067df3e33d08", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ac76912daa5f21039b01e6498a8dfe85b883e1f56e3308b35126067df3e33d08", kill_on_drop: false }`
[INFO] [stdout] ac76912daa5f21039b01e6498a8dfe85b883e1f56e3308b35126067df3e33d08
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 703bf92d9b461fd5851b43f288906c175aa05dc5527745990d78a546dd6dfad7
[INFO] running `Command { std: "docker" "start" "-a" "703bf92d9b461fd5851b43f288906c175aa05dc5527745990d78a546dd6dfad7", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/faeries-0b8a07f55def2877)
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/faeries-98047531cd849c85)
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/ataxx.rs (/opt/rustwide/target/debug/deps/ataxx-3f641f60e29925f6)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test ataxx::delay ... ok
[INFO] [stdout] test ataxx::easy_moves ... ok
[INFO] [stdout] test ataxx::win_in_one ... ok
[INFO] [stderr]      Running tests/droptaxx.rs (/opt/rustwide/target/debug/deps/droptaxx-3ad39589b3a5da11)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.53s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test droptaxx::easy_moves ... ok
[INFO] [stderr]      Running tests/pijersi.rs (/opt/rustwide/target/debug/deps/pijersi-ace0c8ccaa4e2c32)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test pijersi::easy_moves ... ok
[INFO] [stdout] test pijersi::win_in_one ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.26s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/games-b3a1315a1ddd3fbb)
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test general::bitboard::tests::test_border ... ok
[INFO] [stdout] test general::bitboard::tests::test_dist2 ... ok
[INFO] [stdout] test general::bitboard::tests::test_east ... ok
[INFO] [stdout] test general::bitboard::tests::test_from_rank ... ok
[INFO] [stdout] test general::bitboard::tests::test_left_edge ... ok
[INFO] [stdout] test general::bitboard::tests::test_lsb_bb ... ok
[INFO] [stdout] test general::bitboard::tests::test_bottom_edge ... ok
[INFO] [stdout] test general::bitboard::tests::test_count ... ok
[INFO] [stdout] test general::bitboard::tests::test_lsb ... ok
[INFO] [stdout] test general::bitboard::tests::test_north ... ok
[INFO] [stdout] test general::bitboard::tests::test_reach2 ... ok
[INFO] [stdout] test general::bitboard::tests::test_right_edge ... ok
[INFO] [stdout] test general::bitboard::tests::test_south ... ok
[INFO] [stdout] test general::bitboard::tests::test_swap ... ok
[INFO] [stdout] test general::bitboard::tests::test_top_edge ... ok
[INFO] [stdout] test general::bitboard::tests::test_xray_north ... ok
[INFO] [stdout] test general::file::test::indexing ... ok
[INFO] [stdout] test general::hex::tests::get_rank ... ok
[INFO] [stdout] test general::hex::tests::indexing ... ok
[INFO] [stdout] test general::hexbitboard::tests::adjacent ... ok
[INFO] [stdout] test general::file::test::to_string ... ok
[INFO] [stdout] test general::hex::tests::failure_from_string ... ok
[INFO] [stdout] test general::hex::tests::get_file ... ok
[INFO] [stdout] test general::hexbitboard::tests::bitxor ... ok
[INFO] [stdout] test general::hex::tests::to_string ... ok
[INFO] [stdout] test general::hexbitboard::tests::all ... ok
[INFO] [stdout] test general::hexbitboard::tests::down_left ... ok
[INFO] [stdout] test general::hexbitboard::tests::down_right ... ok
[INFO] [stdout] test general::hexbitboard::tests::bitor ... ok
[INFO] [stdout] test general::hexbitboard::tests::left ... ok
[INFO] [stdout] test general::hexbitboard::tests::right ... ok
[INFO] [stdout] test general::hexbitboard::tests::size ... ok
[INFO] [stdout] test general::hexbitboard::tests::test_bottom_edge ... ok
[INFO] [stdout] test general::hexbitboard::tests::test_left_edge ... ok
[INFO] [stdout] test general::hexbitboard::tests::test_right_edge ... ok
[INFO] [stdout] test general::bitboard::tests::test_west ... ok
[INFO] [stdout] test general::hexbitboard::tests::test_top_edge ... ok
[INFO] [stdout] test general::bitboarditer::tests::iter ... ok
[INFO] [stdout] test general::bitboarditer::tests::iter2 ... ok
[INFO] [stdout] test general::hexbitboard::tests::up_left ... ok
[INFO] [stdout] test general::file::test::from_char ... ok
[INFO] [stdout] test general::hexbitboard::tests::up_right ... ok
[INFO] [stdout] test general::file::test::index ... ok
[INFO] [stdout] test general::mailbox::test::basic ... ok
[INFO] [stdout] test general::mailbox::test::option ... ok
[INFO] [stdout] test general::mailbox::test::piece_type ... ok
[INFO] [stdout] test general::mailbox::test::set ... ok
[INFO] [stdout] test general::mailboxiter::tests::iter ... ok
[INFO] [stdout] test general::rank::test::flip ... ok
[INFO] [stdout] test general::rank::test::from_string ... ok
[INFO] [stdout] test general::bitboard::tests::test_ray_north ... ok
[INFO] [stdout] test general::rank::test::index ... ok
[INFO] [stdout] test general::rank::test::indexing ... ok
[INFO] [stdout] test general::rank::test::to_string ... ok
[INFO] [stdout] test general::side::test::indexing ... ok
[INFO] [stdout] test general::side::test::not ... ok
[INFO] [stdout] test general::square::tests::failure_from_string ... ok
[INFO] [stdout] test general::square::tests::from_string ... ok
[INFO] [stdout] test general::square::tests::indexing ... ok
[INFO] [stdout] test general::square::tests::test_directions ... ok
[INFO] [stdout] test general::square::tests::test_flipped ... ok
[INFO] [stdout] test general::bitboard::tests::test_all ... ok
[INFO] [stdout] test general::bitboard::tests::test_adjacent ... ok
[INFO] [stdout] test general::hex::tests::from_string ... ok
[INFO] [stdout] test general::bitboard::tests::test_from_file ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 65 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mod.rs (/opt/rustwide/target/debug/deps/mod-1af21da49857bba4)
[INFO] [stdout] 
[INFO] [stdout] running 58 tests
[INFO] [stdout] test ataxx::count_moves::tests::count_moves ... ok
[INFO] [stdout] test ataxx::counters::tests::counters ... ok
[INFO] [stdout] test ataxx::fens::tests::fens ... ok
[INFO] [stdout] test ataxx::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test ataxx::result::tests::results ... ok
[INFO] [stdout] test chess::count_moves::tests::count_moves ... ok
[INFO] [stdout] test chess::fens::tests::fens ... ok
[INFO] [stdout] test chess::move_parsing::tests::move_parsing ... ok
[INFO] [stdout] test chess::result::tests::results ... ok
[INFO] [stdout] test chess::perft::tests::perft_extra ... ok
[INFO] [stdout] test connect4::counters::tests::counters ... ok
[INFO] [stdout] test chess::counters::tests::counters ... ok
[INFO] [stdout] test connect4::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test connect4::count_moves::tests::count_moves ... ok
[INFO] [stdout] test connect4::fens::tests::fens ... ok
[INFO] [stdout] test connect4::result::tests::results ... ok
[INFO] [stdout] test droptaxx::counters::tests::counters ... ok
[INFO] [stdout] test connect4::perft::tests::perft ... ok
[INFO] [stdout] test droptaxx::fens::tests::fens ... ok
[INFO] [stdout] test droptaxx::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test droptaxx::perft::tests::perft ... ok
[INFO] [stdout] test droptaxx::result::tests::results ... ok
[INFO] [stdout] test gomoku::counters::tests::counters ... ok
[INFO] [stdout] test gomoku::fens::tests::fens ... ok
[INFO] [stdout] test gomoku::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test chess::perft::tests::perft_1 ... ok
[INFO] [stdout] test gomoku::result::tests::results ... ok
[INFO] [stdout] test chess::perft::tests::perft_double_checked ... ok
[INFO] [stdout] test isolation::counters::tests::counters ... ok
[INFO] [stdout] test isolation::fens::tests::fens ... ok
[INFO] [stdout] test isolation::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test isolation::move_parsing::test::move_parsing_fail ... ok
[INFO] [stdout] test chess::perft::tests::perft_enpassant ... ok
[INFO] [stdout] test ataxx::consistency::tests::consistent ... ok
[INFO] [stdout] test isolation::result::tests::results ... ok
[INFO] [stdout] test pijersi::fens::tests::fens ... ok
[INFO] [stdout] test chess::perft::tests::perft_pins ... ok
[INFO] [stdout] test isolation::perft::tests::perft ... ok
[INFO] [stdout] test tak::fens::tests::fens_3x3 ... ok
[INFO] [stdout] test tak::fens::tests::fens_6x6 ... ok
[INFO] [stdout] test connect4::consistency::tests::consistent ... ok
[INFO] [stdout] test droptaxx::consistency::tests::consistent ... ok
[INFO] [stdout] test tak::result::tests::results ... ok
[INFO] [stdout] test tak::consistency::tests::consistency ... ok
[INFO] [stdout] test chess::perft::tests::perft_2 ... ok
[INFO] [stdout] test gomoku::consistency::tests::consistent ... ok
[INFO] [stdout] test gomoku::perft::tests::perft ... ok
[INFO] [stdout] test chess::consistency::tests::consistent ... ok
[INFO] [stdout] test chess::perft::tests::perft_3 ... ok
[INFO] [stdout] test ataxx::perft::tests::perft ... ok
[INFO] [stdout] test tak::perft::tests::perft_3x3 ... ok
[INFO] [stdout] test tak::consistency::tests::consistency_6x6 ... ok
[INFO] [stdout] test isolation::consistency::tests::consistent ... ok
[INFO] [stdout] test chess::perft::tests::perft_4 ... ok
[INFO] [stdout] test chess::perft::tests::perft_5 has been running for over 60 seconds
[INFO] [stdout] test chess::perft::tests::perft_6 has been running for over 60 seconds
[INFO] [stdout] test pijersi::perft::tests::perft has been running for over 60 seconds
[INFO] [stdout] test tak::perft::tests::perft_6x6 has been running for over 60 seconds
[INFO] [stdout] test pijersi::perft::tests::perft ... ok
[INFO] [stdout] test tak::perft::tests::perft_6x6 ... ok
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "703bf92d9b461fd5851b43f288906c175aa05dc5527745990d78a546dd6dfad7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "703bf92d9b461fd5851b43f288906c175aa05dc5527745990d78a546dd6dfad7", kill_on_drop: false }`
[INFO] [stdout] 703bf92d9b461fd5851b43f288906c175aa05dc5527745990d78a546dd6dfad7