[INFO] cloning repository https://github.com/pwnsquad/yobmef [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/pwnsquad/yobmef" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpwnsquad%2Fyobmef", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpwnsquad%2Fyobmef'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 34d64d0095478e3fadf6ad406684295cb616d521 [INFO] testing pwnsquad/yobmef against master#d7bd9cd469ff6871420007f091ef52fc32d2ca99 for pr-121417-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpwnsquad%2Fyobmef" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/pwnsquad/yobmef on toolchain d7bd9cd469ff6871420007f091ef52fc32d2ca99 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/pwnsquad/yobmef [INFO] finished tweaking git repo https://github.com/pwnsquad/yobmef [INFO] tweaked toml for git repo https://github.com/pwnsquad/yobmef written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] crate git repo https://github.com/pwnsquad/yobmef 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" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded ppv-lite86 v0.2.10 [INFO] [stderr] Downloaded rand_core v0.6.1 [INFO] [stderr] Downloaded rand_hc v0.3.0 [INFO] [stderr] Downloaded rand_chacha v0.3.0 [INFO] [stderr] Downloaded wasi v0.10.1+wasi-snapshot-preview1 [INFO] [stderr] Downloaded getrandom v0.2.1 [INFO] [stderr] Downloaded rand v0.8.2 [INFO] [stderr] Downloaded libc v0.2.82 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 129c1d798a936569284b74c76f1df4a2f97db237bd8ef25232cb4873a1da360f [INFO] running `Command { std: "docker" "start" "-a" "129c1d798a936569284b74c76f1df4a2f97db237bd8ef25232cb4873a1da360f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "129c1d798a936569284b74c76f1df4a2f97db237bd8ef25232cb4873a1da360f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "129c1d798a936569284b74c76f1df4a2f97db237bd8ef25232cb4873a1da360f", kill_on_drop: false }` [INFO] [stdout] 129c1d798a936569284b74c76f1df4a2f97db237bd8ef25232cb4873a1da360f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ec7ecfb46ed0be1b1c4e2fea3de0bb9fdfd070c0625cad8c0bada4cda11af098 [INFO] running `Command { std: "docker" "start" "-a" "ec7ecfb46ed0be1b1c4e2fea3de0bb9fdfd070c0625cad8c0bada4cda11af098", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.82 [INFO] [stderr] Compiling getrandom v0.2.1 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.10 [INFO] [stderr] Compiling rand_core v0.6.1 [INFO] [stderr] Compiling rand_chacha v0.3.0 [INFO] [stderr] Compiling rand v0.8.2 [INFO] [stderr] Compiling yobmef v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [optimized + debuginfo] target(s) in 10.34s [INFO] running `Command { std: "docker" "inspect" "ec7ecfb46ed0be1b1c4e2fea3de0bb9fdfd070c0625cad8c0bada4cda11af098", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ec7ecfb46ed0be1b1c4e2fea3de0bb9fdfd070c0625cad8c0bada4cda11af098", kill_on_drop: false }` [INFO] [stdout] ec7ecfb46ed0be1b1c4e2fea3de0bb9fdfd070c0625cad8c0bada4cda11af098 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b86bd125aa3375e58d1093e0bd48c4421f53989259afec9c057a1c1311f0eb61 [INFO] running `Command { std: "docker" "start" "-a" "b86bd125aa3375e58d1093e0bd48c4421f53989259afec9c057a1c1311f0eb61", kill_on_drop: false }` [INFO] [stderr] Compiling yobmef v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: the item `Movement` is imported redundantly [INFO] [stdout] --> src/eval.rs:189:17 [INFO] [stdout] | [INFO] [stdout] 188 | use super::*; [INFO] [stdout] | -------- the item `Movement` is already imported here [INFO] [stdout] 189 | use crate::{chess::Movement, movegen::MoveGen}; [INFO] [stdout] | ^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `moves_test` is imported redundantly [INFO] [stdout] --> src/movegen/king.rs:94:9 [INFO] [stdout] | [INFO] [stdout] 93 | use super::*; [INFO] [stdout] | -------- the item `moves_test` is already imported here [INFO] [stdout] 94 | use crate::movegen::helpers::moves_test; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `Color` is imported redundantly [INFO] [stdout] --> src/movegen/knight.rs:60:9 [INFO] [stdout] | [INFO] [stdout] 59 | use super::*; [INFO] [stdout] | -------- the item `Color` is already imported here [INFO] [stdout] 60 | use crate::chess::Color; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `Board` is imported redundantly [INFO] [stdout] --> src/movegen/mod.rs:117:9 [INFO] [stdout] | [INFO] [stdout] 116 | use super::*; [INFO] [stdout] | -------- the item `Board` is already imported here [INFO] [stdout] 117 | use crate::chess::Board; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `Board` is imported redundantly [INFO] [stdout] --> src/zobrist.rs:56:9 [INFO] [stdout] | [INFO] [stdout] 55 | use super::*; [INFO] [stdout] | -------- the item `Board` is already imported here [INFO] [stdout] 56 | use crate::chess::Board; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 5 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [optimized + debuginfo] target(s) in 7.47s [INFO] running `Command { std: "docker" "inspect" "b86bd125aa3375e58d1093e0bd48c4421f53989259afec9c057a1c1311f0eb61", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b86bd125aa3375e58d1093e0bd48c4421f53989259afec9c057a1c1311f0eb61", kill_on_drop: false }` [INFO] [stdout] b86bd125aa3375e58d1093e0bd48c4421f53989259afec9c057a1c1311f0eb61 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] fef9ffca0388af3ebbf21a20e1a8e0b0b050f45181ea2f55e18568524002428f [INFO] running `Command { std: "docker" "start" "-a" "fef9ffca0388af3ebbf21a20e1a8e0b0b050f45181ea2f55e18568524002428f", kill_on_drop: false }` [INFO] [stderr] warning: the item `Movement` is imported redundantly [INFO] [stderr] --> src/eval.rs:189:17 [INFO] [stderr] | [INFO] [stderr] 188 | use super::*; [INFO] [stderr] | -------- the item `Movement` is already imported here [INFO] [stderr] 189 | use crate::{chess::Movement, movegen::MoveGen}; [INFO] [stderr] | ^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` on by default [INFO] [stderr] [INFO] [stderr] warning: the item `moves_test` is imported redundantly [INFO] [stderr] --> src/movegen/king.rs:94:9 [INFO] [stderr] | [INFO] [stderr] 93 | use super::*; [INFO] [stderr] | -------- the item `moves_test` is already imported here [INFO] [stderr] 94 | use crate::movegen::helpers::moves_test; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: the item `Color` is imported redundantly [INFO] [stderr] --> src/movegen/knight.rs:60:9 [INFO] [stderr] | [INFO] [stderr] 59 | use super::*; [INFO] [stderr] | -------- the item `Color` is already imported here [INFO] [stderr] 60 | use crate::chess::Color; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: the item `Board` is imported redundantly [INFO] [stderr] --> src/movegen/mod.rs:117:9 [INFO] [stderr] | [INFO] [stderr] 116 | use super::*; [INFO] [stderr] | -------- the item `Board` is already imported here [INFO] [stderr] 117 | use crate::chess::Board; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: the item `Board` is imported redundantly [INFO] [stderr] --> src/zobrist.rs:56:9 [INFO] [stderr] | [INFO] [stderr] 55 | use super::*; [INFO] [stderr] | -------- the item `Board` is already imported here [INFO] [stderr] 56 | use crate::chess::Board; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: `yobmef` (lib test) generated 5 warnings [INFO] [stderr] Finished `test` profile [optimized + debuginfo] target(s) in 0.04s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/yobmef-ad60b6ccab7312b5) [INFO] [stdout] [INFO] [stdout] running 86 tests [INFO] [stdout] test bitboard::tests::test_flip_vertical ... ok [INFO] [stdout] test bitboard::tests::test_bitboard_population ... ok [INFO] [stdout] test bitboard::tests::test_bitboard_get_flip ... ok [INFO] [stdout] test bitboard::tests::test_bitboard_or ... ok [INFO] [stdout] test bitboard::tests::test_iterate ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_invalid ... ok [INFO] [stdout] test chess::board::tests::en_passant ... ok [INFO] [stdout] test chess::board::tests::black_castling ... ok [INFO] [stdout] test chess::board::tests::no_castling ... ok [INFO] [stdout] test chess::board::tests::test_color_on ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_castling ... ok [INFO] [stdout] test chess::board::tests::test_fen_endgame ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_e2e4 ... ok [INFO] [stdout] test chess::board::tests::test_get_king_square ... ok [INFO] [stdout] test chess::board::tests::test_make_move_bishop_en_passant ... ok [INFO] [stdout] test chess::board::tests::test_make_move_capture ... ok [INFO] [stdout] test chess::board::tests::test_make_move_castle ... ok [INFO] [stdout] test chess::board::tests::test_make_move_e2e4 ... ok [INFO] [stdout] test chess::board::tests::test_is_in_check ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_starting ... ok [INFO] [stdout] test chess::board::tests::test_make_move_promote ... ok [INFO] [stdout] test chess::board::tests::test_piece_on ... ok [INFO] [stdout] test chess::board::tests::test_to_fen_e2e4 ... ok [INFO] [stdout] test engine::tests::test_position ... ok [INFO] [stdout] test eval::tests::test_get_promise ... ok [INFO] [stdout] test eval::tests::test_get_score_castle ... ok [INFO] [stdout] test eval::tests::test_get_score_e2e4 ... ok [INFO] [stdout] test eval::tests::test_get_score_mate_for_black ... ok [INFO] [stdout] test eval::tests::test_get_score_mate_for_white ... ok [INFO] [stdout] test eval::tests::test_reasonable_d2d4_value_delta ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_black ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_bottomright ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_topleft ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_topright ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_blocked ... ok [INFO] [stdout] test chess::board::tests::test_make_move_remove_castling ... ok [INFO] [stdout] test chess::board::tests::test_to_fen_startpos ... ok [INFO] [stdout] test chess::board::tests::test_valid_after_capture ... ok [INFO] [stdout] test chess::board::tests::test_make_move_en_passant_cleared ... ok [INFO] [stdout] test engine::tests::test_think_time ... ok [INFO] [stdout] test movegen::knight::tests::test_get_knight_moves_edges ... ok [INFO] [stdout] test movegen::knight::tests::test_get_knight_moves_startpos ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_in_check ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_bishop_occupancy_mask ... ok [INFO] [stdout] test movegen::knight::tests::test_get_knight_moves_other_directions ... ok [INFO] [stdout] test movegen::king::tests::test_king_takes_queen ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_through_check ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_unblocked ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_bishop_rays ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_rook_occupancy_mask ... ok [INFO] [stdout] test movegen::pawn::tests::test_black_pawn_promote ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_rook_occupancy_mask_corner ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_rook_rays ... ok [INFO] [stdout] test movegen::pawn::tests::test_get_pawn_en_passant ... ok [INFO] [stdout] test movegen::pawn::tests::test_en_passant_capture ... ok [INFO] [stdout] test movegen::pawn::tests::test_take_pawn_a_file ... ok [INFO] [stdout] test movegen::pawn::tests::test_get_pawn_moves_startpos ... ok [INFO] [stdout] test movegen::pawn::tests::test_get_pawn_moves_endgame ... ok [INFO] [stdout] test movegen::pawn::tests::test_take_pawn_h_file ... ok [INFO] [stdout] test movegen::pawn::tests::test_white_pawn_promote ... ok [INFO] [stdout] test movegen::tests::test_block_check_double_pawn_push ... ok [INFO] [stdout] test movegen::tests::test_endgame ... ok [INFO] [stdout] test movegen::tests::test_iter_attacked ... ok [INFO] [stdout] test movegen::tests::test_block_check_knight ... ok [INFO] [stdout] test movegen::pawn::tests::test_take_own_pawn ... ok [INFO] [stdout] test movegen::magic::tests::test_get_all_sliding_moves ... ok [INFO] [stdout] test movegen::tests::test_move_in_check ... ok [INFO] [stdout] test movegen::tests::test_move_into_check ... ok [INFO] [stdout] test search::tests::test_sort_by_promise_mate ... ignored [INFO] [stdout] test movegen::magic_utils::tests::test_get_questions_and_answers ... ok [INFO] [stdout] test search::tests::test_think_time_100ms ... FAILED [INFO] [stdout] test search::tests::test_think_time_10ms ... FAILED [INFO] [stdout] test uci::tests::test_parse_go ... ok [INFO] [stdout] test uci::tests::test_parse_go_infinite ... ok [INFO] [stdout] test uci::tests::test_parse_go_ponder ... ok [INFO] [stdout] test uci::tests::test_position ... ok [INFO] [stdout] test uci::tests::test_uci ... ok [INFO] [stdout] test zobrist::tests::test_zobrist_collisions ... ignored [INFO] [stdout] test search::tests::test_think_time_1000ms ... ok [INFO] [stdout] test movegen::magic::tests::test_bishop_move_lookup ... ok [INFO] [stdout] test movegen::magic::tests::test_rook_move_lookup ... ok [INFO] [stdout] test search::tests::test_pv_legal_mate_1_white ... ok [INFO] [stdout] test search::tests::test_pv_deepest_startpos ... ok [INFO] [stdout] test search::tests::test_pv_legal_mate_3_black ... ok [INFO] [stdout] test search::tests::test_pv_deepest_mate2 ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- search::tests::test_think_time_100ms stdout ---- [INFO] [stdout] info depth 1 score cp 10 nodes 224 nps 58480 time 3 pv b1c3 b8c6 [INFO] [stdout] info depth 2 score cp 10 nodes 1228 nps 10869 time 112 pv b1c3 b8c6 [INFO] [stdout] thread 'search::tests::test_think_time_100ms' panicked at src/search.rs:421:5: [INFO] [stdout] search elapsed 111986micro > want 100000micro [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55a077372046 - std::backtrace_rs::backtrace::libunwind::trace::hf12510db56099dfb [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55a077372046 - std::backtrace_rs::backtrace::trace_unsynchronized::h94bf70956aef5b0b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55a077372046 - std::sys_common::backtrace::_print_fmt::ha7d7986858ac8c0a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55a077372046 - ::fmt::h372c1ec7a663a1ab [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55a077399c2c - core::fmt::rt::Argument::fmt::hecbea7268359d058 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55a077399c2c - core::fmt::write::h4912438cc1e3f359 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55a07736f88f - std::io::Write::write_fmt::hf3498c39593ae16b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55a077371df4 - std::sys_common::backtrace::_print::h37e973c7e1a1b050 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55a077371df4 - std::sys_common::backtrace::print::h07b06dbedbb17b31 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55a077373aeb - std::panicking::default_hook::{{closure}}::he72022d4e9537bcf [INFO] [stdout] 10: 0x55a0773737bd - std::panicking::default_hook::h04cdc89992c8479b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55a07733f517 - as core::ops::function::Fn>::call::h815f3e5bf035c1a1 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55a07733f517 - test::test_main::{{closure}}::hc6d17bd58ba26ad4 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55a077374120 - as core::ops::function::Fn>::call::hec33fe851e726d9b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55a077374120 - std::panicking::rust_panic_with_hook::hf07491bf03f90571 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55a077373e62 - std::panicking::begin_panic_handler::{{closure}}::h92e879536c12f298 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:660:13 [INFO] [stdout] 16: 0x55a077372526 - std::sys_common::backtrace::__rust_end_short_backtrace::h3800ba1351aa8eea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55a077373b94 - rust_begin_unwind [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55a0772e8845 - core::panicking::panic_fmt::h83446605a04b4380 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55a0772ff489 - yobmef::search::tests::test_think_time_100ms::h8e616c4030584f2f [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:408:21 [INFO] [stdout] 20: 0x55a077308619 - yobmef::search::tests::test_think_time_100ms::{{closure}}::h66784df29d2cef20 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:399:23 [INFO] [stdout] 21: 0x55a077308619 - core::ops::function::FnOnce::call_once::h8cbe1dbc04b34a01 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55a077344cbf - core::ops::function::FnOnce::call_once::h2088a64ea35e9f55 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55a077344cbf - test::__rust_begin_short_backtrace::h3e3262ca517e0f6a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x55a077343890 - test::run_test_in_process::{{closure}}::h6b4258191636288b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x55a077343890 - as core::ops::function::FnOnce<()>>::call_once::h017d06cfb8bed9ea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x55a077343890 - std::panicking::try::do_call::hd5a0dc4bb374d709 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 27: 0x55a077343890 - std::panicking::try::h3168a4ce5bf4579d [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 28: 0x55a077343890 - std::panic::catch_unwind::hb64c62facfdc3e4a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 29: 0x55a077343890 - test::run_test_in_process::h127b8bcabd50528a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x55a077343890 - test::run_test::{{closure}}::h0fdd7a3e6b40f361 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:566:43 [INFO] [stdout] 31: 0x55a07730bc23 - test::run_test::{{closure}}::h48b321fe4dddd4b5 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x55a07730bc23 - std::sys_common::backtrace::__rust_begin_short_backtrace::hc21ce17eb804db15 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 33: 0x55a07731094f - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h09231cb1ff02341f [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 34: 0x55a07731094f - as core::ops::function::FnOnce<()>>::call_once::h39609956c133d940 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x55a07731094f - std::panicking::try::do_call::hc39de059392fc838 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 36: 0x55a07731094f - std::panicking::try::h8e296b7f146034ae [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 37: 0x55a07731094f - std::panic::catch_unwind::hbd1bff6c3e457dcd [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 38: 0x55a07731094f - std::thread::Builder::spawn_unchecked_::{{closure}}::h879be7a53fe7d1d6 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 39: 0x55a07731094f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0fa08594624076ec [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x55a077378385 - as core::ops::function::FnOnce>::call_once::hce6bcef84364f9de [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x55a077378385 - as core::ops::function::FnOnce>::call_once::h5656bb08f6ae0836 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x55a077378385 - std::sys::pal::unix::thread::Thread::new::thread_start::h42aad656803ef398 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 43: 0x7f64de0f4ac3 - [INFO] [stdout] 44: 0x7f64de185bf4 - __clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- search::tests::test_think_time_10ms stdout ---- [INFO] [stdout] info depth 1 score cp 10 nodes 224 nps 60688 time 3 pv b1c3 b8c6 [INFO] [stdout] info depth 2 score cp 10 nodes 323 nps 19167 time 16 pv b1c3 b8c6 [INFO] [stdout] thread 'search::tests::test_think_time_10ms' panicked at src/search.rs:420:5: [INFO] [stdout] search elapsed 15865micro > want 10000micro [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55a077372046 - std::backtrace_rs::backtrace::libunwind::trace::hf12510db56099dfb [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55a077372046 - std::backtrace_rs::backtrace::trace_unsynchronized::h94bf70956aef5b0b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55a077372046 - std::sys_common::backtrace::_print_fmt::ha7d7986858ac8c0a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55a077372046 - ::fmt::h372c1ec7a663a1ab [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55a077399c2c - core::fmt::rt::Argument::fmt::hecbea7268359d058 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55a077399c2c - core::fmt::write::h4912438cc1e3f359 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55a07736f88f - std::io::Write::write_fmt::hf3498c39593ae16b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55a077371df4 - std::sys_common::backtrace::_print::h37e973c7e1a1b050 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55a077371df4 - std::sys_common::backtrace::print::h07b06dbedbb17b31 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55a077373aeb - std::panicking::default_hook::{{closure}}::he72022d4e9537bcf [INFO] [stdout] 10: 0x55a0773737bd - std::panicking::default_hook::h04cdc89992c8479b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55a07733f517 - as core::ops::function::Fn>::call::h815f3e5bf035c1a1 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55a07733f517 - test::test_main::{{closure}}::hc6d17bd58ba26ad4 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55a077374120 - as core::ops::function::Fn>::call::hec33fe851e726d9b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55a077374120 - std::panicking::rust_panic_with_hook::hf07491bf03f90571 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55a077373e62 - std::panicking::begin_panic_handler::{{closure}}::h92e879536c12f298 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:660:13 [INFO] [stdout] 16: 0x55a077372526 - std::sys_common::backtrace::__rust_end_short_backtrace::h3800ba1351aa8eea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55a077373b94 - rust_begin_unwind [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55a0772e8845 - core::panicking::panic_fmt::h83446605a04b4380 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55a0772ff149 - yobmef::search::tests::test_think_time_10ms::h9aebe6456b73518a [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:408:21 [INFO] [stdout] 20: 0x55a077309059 - yobmef::search::tests::test_think_time_10ms::{{closure}}::h0b59796558452196 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:399:23 [INFO] [stdout] 21: 0x55a077309059 - core::ops::function::FnOnce::call_once::hc4482ceece8313bb [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55a077344cbf - core::ops::function::FnOnce::call_once::h2088a64ea35e9f55 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55a077344cbf - test::__rust_begin_short_backtrace::h3e3262ca517e0f6a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x55a077343890 - test::run_test_in_process::{{closure}}::h6b4258191636288b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x55a077343890 - as core::ops::function::FnOnce<()>>::call_once::h017d06cfb8bed9ea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x55a077343890 - std::panicking::try::do_call::hd5a0dc4bb374d709 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 27: 0x55a077343890 - std::panicking::try::h3168a4ce5bf4579d [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 28: 0x55a077343890 - std::panic::catch_unwind::hb64c62facfdc3e4a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 29: 0x55a077343890 - test::run_test_in_process::h127b8bcabd50528a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x55a077343890 - test::run_test::{{closure}}::h0fdd7a3e6b40f361 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:566:43 [INFO] [stdout] 31: 0x55a07730bc23 - test::run_test::{{closure}}::h48b321fe4dddd4b5 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x55a07730bc23 - std::sys_common::backtrace::__rust_begin_short_backtrace::hc21ce17eb804db15 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 33: 0x55a07731094f - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h09231cb1ff02341f [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 34: 0x55a07731094f - as core::ops::function::FnOnce<()>>::call_once::h39609956c133d940 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x55a07731094f - std::panicking::try::do_call::hc39de059392fc838 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 36: 0x55a07731094f - std::panicking::try::h8e296b7f146034ae [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 37: 0x55a07731094f - std::panic::catch_unwind::hbd1bff6c3e457dcd [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 38: 0x55a07731094f - std::thread::Builder::spawn_unchecked_::{{closure}}::h879be7a53fe7d1d6 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 39: 0x55a07731094f - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0fa08594624076ec [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x55a077378385 - as core::ops::function::FnOnce>::call_once::hce6bcef84364f9de [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x55a077378385 - as core::ops::function::FnOnce>::call_once::h5656bb08f6ae0836 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x55a077378385 - std::sys::pal::unix::thread::Thread::new::thread_start::h42aad656803ef398 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 43: 0x7f64de0f4ac3 - [INFO] [stdout] 44: 0x7f64de185bf4 - __clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] search::tests::test_think_time_100ms [INFO] [stdout] search::tests::test_think_time_10ms [INFO] [stdout] [INFO] [stdout] test result: FAILED. 82 passed; 2 failed; 2 ignored; 0 measured; 0 filtered out; finished in 13.14s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "fef9ffca0388af3ebbf21a20e1a8e0b0b050f45181ea2f55e18568524002428f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "fef9ffca0388af3ebbf21a20e1a8e0b0b050f45181ea2f55e18568524002428f", kill_on_drop: false }` [INFO] [stdout] fef9ffca0388af3ebbf21a20e1a8e0b0b050f45181ea2f55e18568524002428f [INFO] testing pwnsquad/yobmef against try#393ef12c970fbc7f294cd96c35cb76f9591bc1d6 for pr-121417-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpwnsquad%2Fyobmef" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/pwnsquad/yobmef on toolchain 393ef12c970fbc7f294cd96c35cb76f9591bc1d6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/pwnsquad/yobmef [INFO] finished tweaking git repo https://github.com/pwnsquad/yobmef [INFO] tweaked toml for git repo https://github.com/pwnsquad/yobmef written to /workspace/builds/worker-7-tc2/source/Cargo.toml [INFO] crate git repo https://github.com/pwnsquad/yobmef 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" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 1a3981c5bd8951b368e72756ed1fd5e333d7c37d5aa891643e6b75ae6bd31227 [INFO] running `Command { std: "docker" "start" "-a" "1a3981c5bd8951b368e72756ed1fd5e333d7c37d5aa891643e6b75ae6bd31227", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "1a3981c5bd8951b368e72756ed1fd5e333d7c37d5aa891643e6b75ae6bd31227", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1a3981c5bd8951b368e72756ed1fd5e333d7c37d5aa891643e6b75ae6bd31227", kill_on_drop: false }` [INFO] [stdout] 1a3981c5bd8951b368e72756ed1fd5e333d7c37d5aa891643e6b75ae6bd31227 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c346ea7fde7535c88ea9969f2a72d4691fa4a3421708916e9125d75f34e0db2e [INFO] running `Command { std: "docker" "start" "-a" "c346ea7fde7535c88ea9969f2a72d4691fa4a3421708916e9125d75f34e0db2e", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.82 [INFO] [stderr] Compiling getrandom v0.2.1 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.10 [INFO] [stderr] Compiling rand_core v0.6.1 [INFO] [stderr] Compiling rand_chacha v0.3.0 [INFO] [stderr] Compiling rand v0.8.2 [INFO] [stderr] Compiling yobmef v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [optimized + debuginfo] target(s) in 9.75s [INFO] running `Command { std: "docker" "inspect" "c346ea7fde7535c88ea9969f2a72d4691fa4a3421708916e9125d75f34e0db2e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c346ea7fde7535c88ea9969f2a72d4691fa4a3421708916e9125d75f34e0db2e", kill_on_drop: false }` [INFO] [stdout] c346ea7fde7535c88ea9969f2a72d4691fa4a3421708916e9125d75f34e0db2e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 264e9af4d3851d67b0799b06eaeeeb870256d9e35978030616e3b05cd0bdff5e [INFO] running `Command { std: "docker" "start" "-a" "264e9af4d3851d67b0799b06eaeeeb870256d9e35978030616e3b05cd0bdff5e", kill_on_drop: false }` [INFO] [stderr] Compiling yobmef v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: the item `Movement` is imported redundantly [INFO] [stdout] --> src/eval.rs:189:17 [INFO] [stdout] | [INFO] [stdout] 188 | use super::*; [INFO] [stdout] | -------- the item `Movement` is already imported here [INFO] [stdout] 189 | use crate::{chess::Movement, movegen::MoveGen}; [INFO] [stdout] | ^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `moves_test` is imported redundantly [INFO] [stdout] --> src/movegen/king.rs:94:9 [INFO] [stdout] | [INFO] [stdout] 93 | use super::*; [INFO] [stdout] | -------- the item `moves_test` is already imported here [INFO] [stdout] 94 | use crate::movegen::helpers::moves_test; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `Color` is imported redundantly [INFO] [stdout] --> src/movegen/knight.rs:60:9 [INFO] [stdout] | [INFO] [stdout] 59 | use super::*; [INFO] [stdout] | -------- the item `Color` is already imported here [INFO] [stdout] 60 | use crate::chess::Color; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `Board` is imported redundantly [INFO] [stdout] --> src/movegen/mod.rs:117:9 [INFO] [stdout] | [INFO] [stdout] 116 | use super::*; [INFO] [stdout] | -------- the item `Board` is already imported here [INFO] [stdout] 117 | use crate::chess::Board; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: the item `Board` is imported redundantly [INFO] [stdout] --> src/zobrist.rs:56:9 [INFO] [stdout] | [INFO] [stdout] 55 | use super::*; [INFO] [stdout] | -------- the item `Board` is already imported here [INFO] [stdout] 56 | use crate::chess::Board; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 5 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [optimized + debuginfo] target(s) in 6.02s [INFO] running `Command { std: "docker" "inspect" "264e9af4d3851d67b0799b06eaeeeb870256d9e35978030616e3b05cd0bdff5e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "264e9af4d3851d67b0799b06eaeeeb870256d9e35978030616e3b05cd0bdff5e", kill_on_drop: false }` [INFO] [stdout] 264e9af4d3851d67b0799b06eaeeeb870256d9e35978030616e3b05cd0bdff5e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4aa614aaf38ec41edb5cf0c2aa61cc7b02c8ba71db6df22bd8455a4ab565443b [INFO] running `Command { std: "docker" "start" "-a" "4aa614aaf38ec41edb5cf0c2aa61cc7b02c8ba71db6df22bd8455a4ab565443b", kill_on_drop: false }` [INFO] [stderr] warning: the item `Movement` is imported redundantly [INFO] [stderr] --> src/eval.rs:189:17 [INFO] [stderr] | [INFO] [stderr] 188 | use super::*; [INFO] [stderr] | -------- the item `Movement` is already imported here [INFO] [stderr] 189 | use crate::{chess::Movement, movegen::MoveGen}; [INFO] [stderr] | ^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` on by default [INFO] [stderr] [INFO] [stderr] warning: the item `moves_test` is imported redundantly [INFO] [stderr] --> src/movegen/king.rs:94:9 [INFO] [stderr] | [INFO] [stderr] 93 | use super::*; [INFO] [stderr] | -------- the item `moves_test` is already imported here [INFO] [stderr] 94 | use crate::movegen::helpers::moves_test; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: the item `Color` is imported redundantly [INFO] [stderr] --> src/movegen/knight.rs:60:9 [INFO] [stderr] | [INFO] [stderr] 59 | use super::*; [INFO] [stderr] | -------- the item `Color` is already imported here [INFO] [stderr] 60 | use crate::chess::Color; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: the item `Board` is imported redundantly [INFO] [stderr] --> src/movegen/mod.rs:117:9 [INFO] [stderr] | [INFO] [stderr] 116 | use super::*; [INFO] [stderr] | -------- the item `Board` is already imported here [INFO] [stderr] 117 | use crate::chess::Board; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: the item `Board` is imported redundantly [INFO] [stderr] --> src/zobrist.rs:56:9 [INFO] [stderr] | [INFO] [stderr] 55 | use super::*; [INFO] [stderr] | -------- the item `Board` is already imported here [INFO] [stderr] 56 | use crate::chess::Board; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: `yobmef` (lib test) generated 5 warnings [INFO] [stderr] Finished `test` profile [optimized + debuginfo] target(s) in 0.07s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/yobmef-ad60b6ccab7312b5) [INFO] [stdout] [INFO] [stdout] running 86 tests [INFO] [stdout] test bitboard::tests::test_flip_vertical ... ok [INFO] [stdout] test bitboard::tests::test_bitboard_population ... ok [INFO] [stdout] test bitboard::tests::test_iterate ... ok [INFO] [stdout] test bitboard::tests::test_bitboard_or ... ok [INFO] [stdout] test bitboard::tests::test_bitboard_get_flip ... ok [INFO] [stdout] test chess::board::tests::no_castling ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_castling ... ok [INFO] [stdout] test chess::board::tests::black_castling ... ok [INFO] [stdout] test chess::board::tests::test_fen_endgame ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_invalid ... ok [INFO] [stdout] test chess::board::tests::test_color_on ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_e2e4 ... ok [INFO] [stdout] test chess::board::tests::en_passant ... ok [INFO] [stdout] test chess::board::tests::test_from_fen_starting ... ok [INFO] [stdout] test chess::board::tests::test_get_king_square ... ok [INFO] [stdout] test chess::board::tests::test_make_move_bishop_en_passant ... ok [INFO] [stdout] test chess::board::tests::test_make_move_capture ... ok [INFO] [stdout] test chess::board::tests::test_make_move_castle ... ok [INFO] [stdout] test chess::board::tests::test_make_move_e2e4 ... ok [INFO] [stdout] test chess::board::tests::test_make_move_en_passant_cleared ... ok [INFO] [stdout] test chess::board::tests::test_valid_after_capture ... ok [INFO] [stdout] test chess::board::tests::test_make_move_promote ... ok [INFO] [stdout] test engine::tests::test_position ... ok [INFO] [stdout] test chess::board::tests::test_make_move_remove_castling ... ok [INFO] [stdout] test chess::board::tests::test_is_in_check ... ok [INFO] [stdout] test chess::board::tests::test_piece_on ... ok [INFO] [stdout] test chess::board::tests::test_to_fen_e2e4 ... ok [INFO] [stdout] test chess::board::tests::test_to_fen_startpos ... ok [INFO] [stdout] test engine::tests::test_think_time ... ok [INFO] [stdout] test eval::tests::test_get_promise ... ok [INFO] [stdout] test eval::tests::test_get_score_e2e4 ... ok [INFO] [stdout] test eval::tests::test_get_score_castle ... ok [INFO] [stdout] test eval::tests::test_get_score_mate_for_white ... ok [INFO] [stdout] test eval::tests::test_reasonable_d2d4_value_delta ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_topleft ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_bottomright ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_topright ... ok [INFO] [stdout] test eval::tests::test_get_score_mate_for_black ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_in_check ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves_black ... ok [INFO] [stdout] test movegen::knight::tests::test_get_knight_moves_edges ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_unblocked ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_through_check ... ok [INFO] [stdout] test movegen::king::tests::test_king_takes_queen ... ok [INFO] [stdout] test movegen::king::tests::test_gen_king_moves ... ok [INFO] [stdout] test movegen::magic::tests::test_get_all_sliding_moves ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_questions_and_answers ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_rook_occupancy_mask ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_rook_occupancy_mask_corner ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_rook_rays ... ok [INFO] [stdout] test movegen::knight::tests::test_get_knight_moves_other_directions ... ok [INFO] [stdout] test movegen::king::tests::test_king_castling_blocked ... ok [INFO] [stdout] test movegen::knight::tests::test_get_knight_moves_startpos ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_bishop_occupancy_mask ... ok [INFO] [stdout] test movegen::magic_utils::tests::test_get_bishop_rays ... ok [INFO] [stdout] test movegen::pawn::tests::test_get_pawn_moves_startpos ... ok [INFO] [stdout] test movegen::pawn::tests::test_take_own_pawn ... ok [INFO] [stdout] test movegen::pawn::tests::test_get_pawn_moves_endgame ... ok [INFO] [stdout] test movegen::pawn::tests::test_black_pawn_promote ... ok [INFO] [stdout] test movegen::pawn::tests::test_en_passant_capture ... ok [INFO] [stdout] test movegen::pawn::tests::test_get_pawn_en_passant ... ok [INFO] [stdout] test movegen::tests::test_block_check_knight ... ok [INFO] [stdout] test movegen::tests::test_iter_attacked ... ok [INFO] [stdout] test movegen::pawn::tests::test_white_pawn_promote ... ok [INFO] [stdout] test movegen::tests::test_block_check_double_pawn_push ... ok [INFO] [stdout] test movegen::tests::test_endgame ... ok [INFO] [stdout] test movegen::tests::test_move_in_check ... ok [INFO] [stdout] test movegen::tests::test_move_into_check ... ok [INFO] [stdout] test movegen::pawn::tests::test_take_pawn_a_file ... ok [INFO] [stdout] test movegen::pawn::tests::test_take_pawn_h_file ... ok [INFO] [stdout] test search::tests::test_sort_by_promise_mate ... ignored [INFO] [stdout] test search::tests::test_think_time_100ms ... FAILED [INFO] [stdout] test search::tests::test_think_time_10ms ... FAILED [INFO] [stdout] test uci::tests::test_parse_go ... ok [INFO] [stdout] test uci::tests::test_parse_go_infinite ... ok [INFO] [stdout] test uci::tests::test_parse_go_ponder ... ok [INFO] [stdout] test uci::tests::test_position ... ok [INFO] [stdout] test uci::tests::test_uci ... ok [INFO] [stdout] test zobrist::tests::test_zobrist_collisions ... ignored [INFO] [stdout] test search::tests::test_think_time_1000ms ... FAILED [INFO] [stdout] test movegen::magic::tests::test_bishop_move_lookup ... ok [INFO] [stdout] test movegen::magic::tests::test_rook_move_lookup ... ok [INFO] [stdout] test search::tests::test_pv_legal_mate_1_white ... ok [INFO] [stdout] test search::tests::test_pv_deepest_startpos ... ok [INFO] [stdout] test search::tests::test_pv_legal_mate_3_black ... ok [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] [stdout] test search::tests::test_pv_deepest_mate2 ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- search::tests::test_think_time_100ms stdout ---- [INFO] [stdout] info depth 1 score cp 10 nodes 224 nps 58004 time 3 pv b1c3 b8c6 [INFO] [stdout] info depth 2 score cp 10 nodes 2006 nps 18409 time 108 pv b1c3 b8c6 [INFO] [stdout] thread 'search::tests::test_think_time_100ms' panicked at src/search.rs:421:5: [INFO] [stdout] search elapsed 107978micro > want 100000micro [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e417c10046 - std::backtrace_rs::backtrace::libunwind::trace::h5c10df0b519d962f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55e417c10046 - std::backtrace_rs::backtrace::trace_unsynchronized::h56122ec150da225d [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e417c10046 - std::sys_common::backtrace::_print_fmt::h8e5f02953967e039 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55e417c10046 - ::fmt::h736f584a9f3f7d78 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55e417c37c2c - core::fmt::rt::Argument::fmt::h0eca9e37bebd875b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55e417c37c2c - core::fmt::write::h57172937379ae112 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55e417c0d7ff - std::io::Write::write_fmt::h48e0063b56f825c3 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55e417c0fdf4 - std::sys_common::backtrace::_print::ha0fb9ef3018dd67f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55e417c0fdf4 - std::sys_common::backtrace::print::h97bc00c4f471ee58 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55e417c11aeb - std::panicking::default_hook::{{closure}}::had669b8c5eb766ef [INFO] [stdout] 10: 0x55e417c117bd - std::panicking::default_hook::hee79edbbfe503541 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55e417bdd517 - as core::ops::function::Fn>::call::hbce7bcf7a5291663 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55e417bdd517 - test::test_main::{{closure}}::hbf7bf4b112234627 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55e417c12120 - as core::ops::function::Fn>::call::h283d0ebbebe64c72 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55e417c12120 - std::panicking::rust_panic_with_hook::ha824647abef1aff2 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55e417c11e62 - std::panicking::begin_panic_handler::{{closure}}::h34c7493359eae8d6 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:660:13 [INFO] [stdout] 16: 0x55e417c10526 - std::sys_common::backtrace::__rust_end_short_backtrace::h8f5a11b92972236f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55e417c11b94 - rust_begin_unwind [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55e417b86845 - core::panicking::panic_fmt::h4f7dd4ba7fa8b3c9 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55e417b9d489 - yobmef::search::tests::test_think_time_100ms::h55d36ed1fa4f72b6 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:408:21 [INFO] [stdout] 20: 0x55e417ba6859 - yobmef::search::tests::test_think_time_100ms::{{closure}}::h22023b6367931e67 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:399:23 [INFO] [stdout] 21: 0x55e417ba6859 - core::ops::function::FnOnce::call_once::hb658a941365f238d [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55e417be2d1f - core::ops::function::FnOnce::call_once::h263d60e66e97ae21 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55e417be2d1f - test::__rust_begin_short_backtrace::hfd27401b46e2c35b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x55e417be1890 - test::run_test_in_process::{{closure}}::h6f15adc4862466a2 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x55e417be1890 - as core::ops::function::FnOnce<()>>::call_once::haaf085868eec590b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x55e417be1890 - std::panicking::try::do_call::he863b9ffdd9dccd8 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:555:40 [INFO] [stdout] 27: 0x55e417be1890 - std::panicking::try::h00518ddc92157d69 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:519:19 [INFO] [stdout] 28: 0x55e417be1890 - std::panic::catch_unwind::ha54d881a3136a844 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panic.rs:142:14 [INFO] [stdout] 29: 0x55e417be1890 - test::run_test_in_process::he6969e4cdcd00ab9 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x55e417be1890 - test::run_test::{{closure}}::ha7bd2350e4208460 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:566:43 [INFO] [stdout] 31: 0x55e417ba9c23 - test::run_test::{{closure}}::h7e4f6d8c61dc1fc5 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x55e417ba9c23 - std::sys_common::backtrace::__rust_begin_short_backtrace::h7971b3168cdfe030 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 33: 0x55e417bae8bf - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h940fea02fa91722e [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 34: 0x55e417bae8bf - as core::ops::function::FnOnce<()>>::call_once::hc9108612b0b55d6f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x55e417bae8bf - std::panicking::try::do_call::h088487cc7181ed41 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:555:40 [INFO] [stdout] 36: 0x55e417bae8bf - std::panicking::try::hbe658ba86d10aeac [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:519:19 [INFO] [stdout] 37: 0x55e417bae8bf - std::panic::catch_unwind::hbcfe75becdd3312a [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panic.rs:142:14 [INFO] [stdout] 38: 0x55e417bae8bf - std::thread::Builder::spawn_unchecked_::{{closure}}::h5da9b0997b04a0b6 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 39: 0x55e417bae8bf - core::ops::function::FnOnce::call_once{{vtable.shim}}::h282e25baa8bce548 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x55e417c16385 - as core::ops::function::FnOnce>::call_once::h7b00d7973698e306 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x55e417c16385 - as core::ops::function::FnOnce>::call_once::ha210a4482bc8347c [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x55e417c16385 - std::sys::pal::unix::thread::Thread::new::thread_start::h2e04f672130bcc99 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 43: 0x7f8570906ac3 - [INFO] [stdout] 44: 0x7f8570997bf4 - __clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- search::tests::test_think_time_10ms stdout ---- [INFO] [stdout] info depth 1 score cp 10 nodes 224 nps 55370 time 4 pv b1c3 b8c6 [INFO] [stdout] info depth 2 score cp 10 nodes 579 nps 15669 time 36 pv b1c3 b8c6 [INFO] [stdout] thread 'search::tests::test_think_time_10ms' panicked at src/search.rs:420:5: [INFO] [stdout] search elapsed 35962micro > want 10000micro [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e417c10046 - std::backtrace_rs::backtrace::libunwind::trace::h5c10df0b519d962f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55e417c10046 - std::backtrace_rs::backtrace::trace_unsynchronized::h56122ec150da225d [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e417c10046 - std::sys_common::backtrace::_print_fmt::h8e5f02953967e039 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55e417c10046 - ::fmt::h736f584a9f3f7d78 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55e417c37c2c - core::fmt::rt::Argument::fmt::h0eca9e37bebd875b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55e417c37c2c - core::fmt::write::h57172937379ae112 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55e417c0d7ff - std::io::Write::write_fmt::h48e0063b56f825c3 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55e417c0fdf4 - std::sys_common::backtrace::_print::ha0fb9ef3018dd67f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55e417c0fdf4 - std::sys_common::backtrace::print::h97bc00c4f471ee58 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55e417c11aeb - std::panicking::default_hook::{{closure}}::had669b8c5eb766ef [INFO] [stdout] 10: 0x55e417c117bd - std::panicking::default_hook::hee79edbbfe503541 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55e417bdd517 - as core::ops::function::Fn>::call::hbce7bcf7a5291663 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55e417bdd517 - test::test_main::{{closure}}::hbf7bf4b112234627 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55e417c12120 - as core::ops::function::Fn>::call::h283d0ebbebe64c72 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55e417c12120 - std::panicking::rust_panic_with_hook::ha824647abef1aff2 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55e417c11e62 - std::panicking::begin_panic_handler::{{closure}}::h34c7493359eae8d6 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:660:13 [INFO] [stdout] 16: 0x55e417c10526 - std::sys_common::backtrace::__rust_end_short_backtrace::h8f5a11b92972236f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55e417c11b94 - rust_begin_unwind [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55e417b86845 - core::panicking::panic_fmt::h4f7dd4ba7fa8b3c9 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55e417b9d149 - yobmef::search::tests::test_think_time_10ms::h94079cf01699bcc8 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:408:21 [INFO] [stdout] 20: 0x55e417ba4c09 - yobmef::search::tests::test_think_time_10ms::{{closure}}::ha45e4e149ac9fb31 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:399:23 [INFO] [stdout] 21: 0x55e417ba4c09 - core::ops::function::FnOnce::call_once::h37d60dafa421e655 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55e417be2d1f - core::ops::function::FnOnce::call_once::h263d60e66e97ae21 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55e417be2d1f - test::__rust_begin_short_backtrace::hfd27401b46e2c35b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x55e417be1890 - test::run_test_in_process::{{closure}}::h6f15adc4862466a2 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x55e417be1890 - as core::ops::function::FnOnce<()>>::call_once::haaf085868eec590b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x55e417be1890 - std::panicking::try::do_call::he863b9ffdd9dccd8 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:555:40 [INFO] [stdout] 27: 0x55e417be1890 - std::panicking::try::h00518ddc92157d69 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:519:19 [INFO] [stdout] 28: 0x55e417be1890 - std::panic::catch_unwind::ha54d881a3136a844 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panic.rs:142:14 [INFO] [stdout] 29: 0x55e417be1890 - test::run_test_in_process::he6969e4cdcd00ab9 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x55e417be1890 - test::run_test::{{closure}}::ha7bd2350e4208460 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:566:43 [INFO] [stdout] 31: 0x55e417ba9c23 - test::run_test::{{closure}}::h7e4f6d8c61dc1fc5 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x55e417ba9c23 - std::sys_common::backtrace::__rust_begin_short_backtrace::h7971b3168cdfe030 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 33: 0x55e417bae8bf - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h940fea02fa91722e [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 34: 0x55e417bae8bf - as core::ops::function::FnOnce<()>>::call_once::hc9108612b0b55d6f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x55e417bae8bf - std::panicking::try::do_call::h088487cc7181ed41 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:555:40 [INFO] [stdout] 36: 0x55e417bae8bf - std::panicking::try::hbe658ba86d10aeac [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:519:19 [INFO] [stdout] 37: 0x55e417bae8bf - std::panic::catch_unwind::hbcfe75becdd3312a [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panic.rs:142:14 [INFO] [stdout] 38: 0x55e417bae8bf - std::thread::Builder::spawn_unchecked_::{{closure}}::h5da9b0997b04a0b6 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 39: 0x55e417bae8bf - core::ops::function::FnOnce::call_once{{vtable.shim}}::h282e25baa8bce548 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x55e417c16385 - as core::ops::function::FnOnce>::call_once::h7b00d7973698e306 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x55e417c16385 - as core::ops::function::FnOnce>::call_once::ha210a4482bc8347c [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x55e417c16385 - std::sys::pal::unix::thread::Thread::new::thread_start::h2e04f672130bcc99 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 43: 0x7f8570906ac3 - [INFO] [stdout] 44: 0x7f8570997bf4 - __clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- search::tests::test_think_time_1000ms stdout ---- [INFO] [stdout] info depth 1 score cp 10 nodes 224 nps 58738 time 3 pv b1c3 b8c6 [INFO] [stdout] info depth 2 score cp 40 nodes 2753 nps 14424 time 190 pv b1c3 b8c6 g1f3 [INFO] [stdout] info depth 3 score cp 40 nodes 19511 nps 19260 time 1012 pv b1c3 b8c6 g1f3 [INFO] [stdout] thread 'search::tests::test_think_time_1000ms' panicked at src/search.rs:422:5: [INFO] [stdout] search elapsed 1012005micro > want 1000000micro [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e417c10046 - std::backtrace_rs::backtrace::libunwind::trace::h5c10df0b519d962f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x55e417c10046 - std::backtrace_rs::backtrace::trace_unsynchronized::h56122ec150da225d [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e417c10046 - std::sys_common::backtrace::_print_fmt::h8e5f02953967e039 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55e417c10046 - ::fmt::h736f584a9f3f7d78 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55e417c37c2c - core::fmt::rt::Argument::fmt::h0eca9e37bebd875b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x55e417c37c2c - core::fmt::write::h57172937379ae112 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x55e417c0d7ff - std::io::Write::write_fmt::h48e0063b56f825c3 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x55e417c0fdf4 - std::sys_common::backtrace::_print::ha0fb9ef3018dd67f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55e417c0fdf4 - std::sys_common::backtrace::print::h97bc00c4f471ee58 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55e417c11aeb - std::panicking::default_hook::{{closure}}::had669b8c5eb766ef [INFO] [stdout] 10: 0x55e417c117bd - std::panicking::default_hook::hee79edbbfe503541 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55e417bdd517 - as core::ops::function::Fn>::call::hbce7bcf7a5291663 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55e417bdd517 - test::test_main::{{closure}}::hbf7bf4b112234627 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55e417c12120 - as core::ops::function::Fn>::call::h283d0ebbebe64c72 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x55e417c12120 - std::panicking::rust_panic_with_hook::ha824647abef1aff2 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x55e417c11e62 - std::panicking::begin_panic_handler::{{closure}}::h34c7493359eae8d6 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:660:13 [INFO] [stdout] 16: 0x55e417c10526 - std::sys_common::backtrace::__rust_end_short_backtrace::h8f5a11b92972236f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55e417c11b94 - rust_begin_unwind [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55e417b86845 - core::panicking::panic_fmt::h4f7dd4ba7fa8b3c9 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55e417b9d7c7 - yobmef::search::tests::test_think_time_1000ms::h4e75b0549ff7f1dd [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:408:21 [INFO] [stdout] 20: 0x55e417ba7989 - yobmef::search::tests::test_think_time_1000ms::{{closure}}::h26deffe8355af303 [INFO] [stdout] at /opt/rustwide/workdir/src/search.rs:399:23 [INFO] [stdout] 21: 0x55e417ba7989 - core::ops::function::FnOnce::call_once::hf898bfc484628e3b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55e417be2d1f - core::ops::function::FnOnce::call_once::h263d60e66e97ae21 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55e417be2d1f - test::__rust_begin_short_backtrace::hfd27401b46e2c35b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:620:18 [INFO] [stdout] 24: 0x55e417be1890 - test::run_test_in_process::{{closure}}::h6f15adc4862466a2 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:643:60 [INFO] [stdout] 25: 0x55e417be1890 - as core::ops::function::FnOnce<()>>::call_once::haaf085868eec590b [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x55e417be1890 - std::panicking::try::do_call::he863b9ffdd9dccd8 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:555:40 [INFO] [stdout] 27: 0x55e417be1890 - std::panicking::try::h00518ddc92157d69 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:519:19 [INFO] [stdout] 28: 0x55e417be1890 - std::panic::catch_unwind::ha54d881a3136a844 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panic.rs:142:14 [INFO] [stdout] 29: 0x55e417be1890 - test::run_test_in_process::he6969e4cdcd00ab9 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:643:27 [INFO] [stdout] 30: 0x55e417be1890 - test::run_test::{{closure}}::ha7bd2350e4208460 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:566:43 [INFO] [stdout] 31: 0x55e417ba9c23 - test::run_test::{{closure}}::h7e4f6d8c61dc1fc5 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/test/src/lib.rs:594:41 [INFO] [stdout] 32: 0x55e417ba9c23 - std::sys_common::backtrace::__rust_begin_short_backtrace::h7971b3168cdfe030 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 33: 0x55e417bae8bf - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h940fea02fa91722e [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 34: 0x55e417bae8bf - as core::ops::function::FnOnce<()>>::call_once::hc9108612b0b55d6f [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x55e417bae8bf - std::panicking::try::do_call::h088487cc7181ed41 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:555:40 [INFO] [stdout] 36: 0x55e417bae8bf - std::panicking::try::hbe658ba86d10aeac [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panicking.rs:519:19 [INFO] [stdout] 37: 0x55e417bae8bf - std::panic::catch_unwind::hbcfe75becdd3312a [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/panic.rs:142:14 [INFO] [stdout] 38: 0x55e417bae8bf - std::thread::Builder::spawn_unchecked_::{{closure}}::h5da9b0997b04a0b6 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 39: 0x55e417bae8bf - core::ops::function::FnOnce::call_once{{vtable.shim}}::h282e25baa8bce548 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x55e417c16385 - as core::ops::function::FnOnce>::call_once::h7b00d7973698e306 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x55e417c16385 - as core::ops::function::FnOnce>::call_once::ha210a4482bc8347c [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x55e417c16385 - std::sys::pal::unix::thread::Thread::new::thread_start::h2e04f672130bcc99 [INFO] [stdout] at /rustc/393ef12c970fbc7f294cd96c35cb76f9591bc1d6/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 43: 0x7f8570906ac3 - [INFO] [stdout] 44: 0x7f8570997bf4 - __clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] search::tests::test_think_time_1000ms [INFO] [stdout] search::tests::test_think_time_100ms [INFO] [stdout] search::tests::test_think_time_10ms [INFO] [stdout] [INFO] [stdout] test result: FAILED. 81 passed; 3 failed; 2 ignored; 0 measured; 0 filtered out; finished in 9.33s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "4aa614aaf38ec41edb5cf0c2aa61cc7b02c8ba71db6df22bd8455a4ab565443b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4aa614aaf38ec41edb5cf0c2aa61cc7b02c8ba71db6df22bd8455a4ab565443b", kill_on_drop: false }` [INFO] [stdout] 4aa614aaf38ec41edb5cf0c2aa61cc7b02c8ba71db6df22bd8455a4ab565443b