[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 try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkz04px%2Ffaeries" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/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-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/kz04px/faeries on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 35a3e09ac30d4204079fd4cc960e14eda6d0702f65b0f8603fca362d58fc0e05
[INFO] running `Command { std: "docker" "start" "-a" "35a3e09ac30d4204079fd4cc960e14eda6d0702f65b0f8603fca362d58fc0e05", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "35a3e09ac30d4204079fd4cc960e14eda6d0702f65b0f8603fca362d58fc0e05", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "35a3e09ac30d4204079fd4cc960e14eda6d0702f65b0f8603fca362d58fc0e05", kill_on_drop: false }`
[INFO] [stdout] 35a3e09ac30d4204079fd4cc960e14eda6d0702f65b0f8603fca362d58fc0e05
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 76b623d9d471851eab3c58df90a350538820acddaf03b7b2be1face023c7d8c8
[INFO] running `Command { std: "docker" "start" "-a" "76b623d9d471851eab3c58df90a350538820acddaf03b7b2be1face023c7d8c8", 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 9.20s
[INFO] running `Command { std: "docker" "inspect" "76b623d9d471851eab3c58df90a350538820acddaf03b7b2be1face023c7d8c8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "76b623d9d471851eab3c58df90a350538820acddaf03b7b2be1face023c7d8c8", kill_on_drop: false }`
[INFO] [stdout] 76b623d9d471851eab3c58df90a350538820acddaf03b7b2be1face023c7d8c8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 856869b5bbc7719137ddb8c9439961b426f66aca45578e4c3b8d6accd7fbfc84
[INFO] running `Command { std: "docker" "start" "-a" "856869b5bbc7719137ddb8c9439961b426f66aca45578e4c3b8d6accd7fbfc84", 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 `test` profile [unoptimized + debuginfo] target(s) in 5.41s
[INFO] running `Command { std: "docker" "inspect" "856869b5bbc7719137ddb8c9439961b426f66aca45578e4c3b8d6accd7fbfc84", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "856869b5bbc7719137ddb8c9439961b426f66aca45578e4c3b8d6accd7fbfc84", kill_on_drop: false }`
[INFO] [stdout] 856869b5bbc7719137ddb8c9439961b426f66aca45578e4c3b8d6accd7fbfc84
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5c0519c67c34eedd6294254d951f8e3d3dcfb089b80cda78302d5e9872a342c5
[INFO] running `Command { std: "docker" "start" "-a" "5c0519c67c34eedd6294254d951f8e3d3dcfb089b80cda78302d5e9872a342c5", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/faeries-39e8ed929edfec00)
[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.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/faeries-297ce026055ff906)
[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] [stderr]      Running tests/ataxx.rs (/opt/rustwide/target/debug/deps/ataxx-6e7cde1ba5b3291d)
[INFO] [stdout] test ataxx::delay ... ok
[INFO] [stdout] test ataxx::easy_moves ... ok
[INFO] [stdout] test ataxx::win_in_one ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.48s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/droptaxx.rs (/opt/rustwide/target/debug/deps/droptaxx-cb4a1ed44f342114)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test droptaxx::easy_moves ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pijersi.rs (/opt/rustwide/target/debug/deps/pijersi-c06f201020765901)
[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.39s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/games-ca338a87f1b71190)
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test general::bitboard::tests::test_adjacent ... ok
[INFO] [stdout] test general::bitboard::tests::test_border ... ok
[INFO] [stdout] test general::bitboard::tests::test_all ... 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_dist2 ... ok
[INFO] [stdout] test general::bitboard::tests::test_east ... ok
[INFO] [stdout] test general::bitboard::tests::test_from_file ... 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 ... ok
[INFO] [stdout] test general::bitboard::tests::test_lsb_bb ... ok
[INFO] [stdout] test general::bitboard::tests::test_ray_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_west ... ok
[INFO] [stdout] test general::bitboard::tests::test_xray_north ... ok
[INFO] [stdout] test general::bitboarditer::tests::iter ... ok
[INFO] [stdout] test general::bitboarditer::tests::iter2 ... ok
[INFO] [stdout] test general::file::test::index ... ok
[INFO] [stdout] test general::file::test::from_char ... ok
[INFO] [stdout] test general::file::test::indexing ... 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::from_string ... ok
[INFO] [stdout] test general::hex::tests::get_rank ... ok
[INFO] [stdout] test general::hex::tests::get_file ... ok
[INFO] [stdout] test general::bitboard::tests::test_north ... ok
[INFO] [stdout] test general::hex::tests::to_string ... ok
[INFO] [stdout] test general::hex::tests::indexing ... ok
[INFO] [stdout] test general::hexbitboard::tests::down_right ... ok
[INFO] [stdout] test general::hexbitboard::tests::bitor ... ok
[INFO] [stdout] test general::hexbitboard::tests::all ... ok
[INFO] [stdout] test general::hexbitboard::tests::size ... ok
[INFO] [stdout] test general::hexbitboard::tests::adjacent ... 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::hexbitboard::tests::up_left ... ok
[INFO] [stdout] test general::hexbitboard::tests::test_top_edge ... ok
[INFO] [stdout] test general::hexbitboard::tests::up_right ... 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::mailboxiter::tests::iter ... ok
[INFO] [stdout] test general::mailbox::test::set ... ok
[INFO] [stdout] test general::rank::test::flip ... ok
[INFO] [stdout] test general::rank::test::index ... ok
[INFO] [stdout] test general::rank::test::from_string ... 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::indexing ... ok
[INFO] [stdout] test general::square::tests::from_string ... ok
[INFO] [stdout] test general::square::tests::test_directions ... ok
[INFO] [stdout] test general::square::tests::test_flipped ... ok
[INFO] [stdout] test general::hexbitboard::tests::left ... ok
[INFO] [stdout] test general::hexbitboard::tests::right ... ok
[INFO] [stdout] test general::hexbitboard::tests::bitxor ... ok
[INFO] [stdout] test general::hexbitboard::tests::down_left ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 65 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mod.rs (/opt/rustwide/target/debug/deps/mod-a0c673ed7f3455ef)
[INFO] [stdout] 
[INFO] [stdout] running 58 tests
[INFO] [stdout] test ataxx::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test ataxx::result::tests::results ... ok
[INFO] [stdout] test ataxx::counters::tests::counters ... ok
[INFO] [stdout] test ataxx::count_moves::tests::count_moves ... ok
[INFO] [stdout] test ataxx::fens::tests::fens ... ok
[INFO] [stdout] test chess::counters::tests::counters ... ok
[INFO] [stdout] test chess::fens::tests::fens ... ok
[INFO] [stdout] test chess::move_parsing::tests::move_parsing ... ok
[INFO] [stdout] test chess::count_moves::tests::count_moves ... ok
[INFO] [stdout] test chess::perft::tests::perft_1 ... ok
[INFO] [stdout] test ataxx::consistency::tests::consistent ... ok
[INFO] [stdout] test chess::perft::tests::perft_double_checked ... ok
[INFO] [stdout] test chess::perft::tests::perft_enpassant ... ok
[INFO] [stdout] test chess::perft::tests::perft_extra ... ok
[INFO] [stdout] test chess::perft::tests::perft_2 ... ok
[INFO] [stdout] test chess::result::tests::results ... ok
[INFO] [stdout] test connect4::consistency::tests::consistent ... ok
[INFO] [stdout] test connect4::count_moves::tests::count_moves ... ok
[INFO] [stdout] test connect4::counters::tests::counters ... ok
[INFO] [stdout] test connect4::fens::tests::fens ... ok
[INFO] [stdout] test connect4::move_parsing::test::move_parsing ... ok
[INFO] [stdout] test connect4::perft::tests::perft ... ok
[INFO] [stdout] test connect4::result::tests::results ... ok
[INFO] [stdout] test chess::perft::tests::perft_pins ... ok
[INFO] [stdout] test droptaxx::counters::tests::counters ... 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 droptaxx::consistency::tests::consistent ... 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 gomoku::consistency::tests::consistent ... ok
[INFO] [stdout] test gomoku::result::tests::results ... ok
[INFO] [stdout] test chess::consistency::tests::consistent ... 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 isolation::perft::tests::perft ... ok
[INFO] [stdout] test isolation::result::tests::results ... ok
[INFO] [stdout] test pijersi::fens::tests::fens ... ok
[INFO] [stdout] test gomoku::perft::tests::perft ... ok
[INFO] [stdout] test tak::consistency::tests::consistency ... ok
[INFO] [stdout] test chess::perft::tests::perft_3 ... ok
[INFO] [stdout] test tak::fens::tests::fens_3x3 ... ok
[INFO] [stdout] test tak::fens::tests::fens_6x6 ... ok
[INFO] [stdout] test ataxx::perft::tests::perft ... ok
[INFO] [stdout] test tak::perft::tests::perft_3x3 ... ok
[INFO] [stdout] test tak::result::tests::results ... 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 has been running for over 60 seconds
[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 chess::perft::tests::perft_4 ... ok
[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" "5c0519c67c34eedd6294254d951f8e3d3dcfb089b80cda78302d5e9872a342c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c0519c67c34eedd6294254d951f8e3d3dcfb089b80cda78302d5e9872a342c5", kill_on_drop: false }`
[INFO] [stdout] 5c0519c67c34eedd6294254d951f8e3d3dcfb089b80cda78302d5e9872a342c5
