Public/Chess_Probabilistic_Programming
2
0
Fork 0
Code Issues Pull Requests Actions Packages Projects Releases Wiki Activity

added base classes for mcts and strategy

Browse Source
This commit is contained in:
Lukas Kuess
2024-01-26 18:01:11 +01:00
parent 62410d239f
commit e4fa09bac3
6 changed files with 1240 additions and 40 deletions
Download Patch File Download Diff File Expand all files Collapse all files

21
mcts.py → classic_mcts.py
View File

@@ -2,10 +2,31 @@ import chess
import random import random
import eval import eval
import engine import engine
import IStrategy
import numpy as np import numpy as np
from abc import ABC, abstractmethod
class IMcts(ABC):
def __init__(self, board: chess.Board, strategy: IStrategy):
self.board = board
@abstractmethod
def sample(self, runs: int = 1000) -> None:
pass
@abstractmethod
def apply_move(self, move: chess.Move) -> None:
pass
@abstractmethod
def get_children(self) -> list['Mcts']:
pass
class MCTSNode: class MCTSNode:
def __init__(self, board: chess.Board, parent = None, move: chess.Move | None = None, random_state: int | None = None): def __init__(self, board: chess.Board, parent = None, move: chess.Move | None = None, random_state: int | None = None):
self.random = random.Random(random_state) self.random = random.Random(random_state)
self.board = board self.board = board

21
i_mcts.py Normal file
View File

@@ -0,0 +1,21 @@
import chess
from abc import ABC, abstractmethod
from IStrategy import IStrategy
class IMcts(ABC):
def __init__(self, board: chess.Board, strategy: IStrategy):
self.board = board
@abstractmethod
def sample(self, runs: int = 1000) -> None:
pass
@abstractmethod
def apply_move(self, move: chess.Move) -> None:
pass
@abstractmethod
def get_children(self) -> list['Mcts']:
pass

8
i_strategy.py Normal file
View File

@@ -0,0 +1,8 @@
from abc import ABC, abstractmethod
# TODO extend class
class IStrategy(ABC):
@abstractmethod
def pick_next_move(self, ):
pass

20
lichess_bot/lib/strategies.py
View File

@@ -12,6 +12,7 @@ from lib.engine_wrapper import MinimalEngine, MOVE
from typing import Any from typing import Any
import logging import logging
from engines import engine
# Use this logger variable to print messages to the console or log files. # Use this logger variable to print messages to the console or log files.
# logger.info("message") will always print "message" to the console or log file. # logger.info("message") will always print "message" to the console or log file.
@@ -93,3 +94,22 @@ class ComboEngine(ExampleEngine):
possible_moves.sort(key=str) possible_moves.sort(key=str)
move = possible_moves[0] move = possible_moves[0]
return PlayResult(move, None, draw_offered=draw_offered) return PlayResult(move, None, draw_offered=draw_offered)
class ProbStockfish(MinimalEngine):
def search(self, board: chess.Board, time_limit: chess.engine.Limit, ponder: bool, draw_offered: bool,
root_moves: MOVE) -> chess.engine.PlayResult:
moves = {}
untried_moves = list(board.legal_moves)
for move in untried_moves:
mean, std = engine.simulate_stockfish_prob(board.copy(), move, 10, 2)
moves[move] = (mean, std)
if mean == 100_000 and std == 0:
return chess.engine.PlayResult(move, None)
return self.get_best_move(moves)
def get_best_move(self, moves: dict) -> chess.engine.PlayResult:
best_avg = max(moves.items(), key=lambda m: m[1][0])
next_move = best_avg[0]
return chess.engine.PlayResult(next_move, None)

665
lichess_bot/lichess_bot_auto_logs/old.log
View File

@@ -1,17 +1,17 @@
2024-01-25 17:43:00,988 __main__ (lichess-bot.py:1023) INFO 2024-01-26 16:56:16,511 __main__ (lichess-bot.py:1023) INFO
. _/| . _/|
. // o\ . // o\
. || ._) lichess_bot 2024.1.21.1 . || ._) lichess_bot 2024.1.21.1
. //__\ . //__\
. )___( Play on Lichess with a bot . )___( Play on Lichess with a bot
2024-01-25 17:43:01,008 lib.config (config.py:261) DEBUG Config: 2024-01-26 16:56:16,531 lib.config (config.py:261) DEBUG Config:
token: logger token: logger
url: https://lichess.org/ url: https://lichess.org/
engine: engine:
dir: ../engines/ dir: ./engines
name: ProbStockfish name: ProbStockfish
working_dir: ./ working_dir: ''
protocol: homemade protocol: homemade
ponder: true ponder: true
polyglot: polyglot:
@@ -137,14 +137,14 @@ matchmaking:
challenge_mode: random challenge_mode: random
challenge_filter: none challenge_filter: none
2024-01-25 17:43:01,008 lib.config (config.py:262) DEBUG ==================== 2024-01-26 16:56:16,531 lib.config (config.py:262) DEBUG ====================
2024-01-25 17:43:01,012 lib.config (config.py:261) DEBUG Config: 2024-01-26 16:56:16,536 lib.config (config.py:261) DEBUG Config:
token: logger token: logger
url: https://lichess.org/ url: https://lichess.org/
engine: engine:
dir: ../engines/ dir: ./engines
name: ProbStockfish name: ProbStockfish
working_dir: ./ working_dir: /home/luke/projects/pp-project/chess-engine-pp/lichess_bot
protocol: homemade protocol: homemade
ponder: true ponder: true
polyglot: polyglot:
@@ -282,18 +282,643 @@ matchmaking:
overrides: {} overrides: {}
pgn_file_grouping: game pgn_file_grouping: game
2024-01-25 17:43:01,012 lib.config (config.py:262) DEBUG ==================== 2024-01-26 16:56:16,536 lib.config (config.py:262) DEBUG ====================
2024-01-25 17:43:01,012 __main__ (lichess-bot.py:1026) INFO Checking engine configuration ... 2024-01-26 16:56:16,536 __main__ (lichess-bot.py:1026) INFO Checking engine configuration ...
2024-01-25 17:43:01,015 __main__ (lichess-bot.py:1091) ERROR Quitting lichess_bot due to an error: 2024-01-26 16:56:16,586 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:16,586 __main__ (lichess-bot.py:1029) INFO Engine configuration OK
2024-01-26 16:56:16,588 urllib3.connectionpool (connectionpool.py:1052) DEBUG Starting new HTTPS connection (1): lichess.org:443
2024-01-26 16:56:16,733 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/token/test HTTP/1.1" 200 None
2024-01-26 16:56:16,764 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/account HTTP/1.1" 200 None
2024-01-26 16:56:16,764 __main__ (lichess-bot.py:1038) INFO Welcome probabilistic-bot!
2024-01-26 16:56:16,765 __main__ (lichess-bot.py:206) INFO You're now connected to https://lichess.org/ and awaiting challenges.
2024-01-26 16:56:17,110 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/account/playing HTTP/1.1" 200 None
2024-01-26 16:56:17,365 __main__ (lichess-bot.py:370) DEBUG Event: {'type': 'gameStart', 'game': {'fullId': '4bebkUSq8GI3', 'gameId': '4bebkUSq', 'fen': 'rnbqkbnr/pppppppp/8/8/8/8/PPPPPPPP/RNBQKBNR w KQkq - 0 1', 'color': 'white', 'lastMove': '', 'source': 'friend', 'status': {'id': 20, 'name': 'started'}, 'variant': {'key': 'standard', 'name': 'Standard'}, 'speed': 'rapid', 'perf': 'rapid', 'rated': False, 'hasMoved': False, 'opponent': {'id': 'luk3k', 'username': 'luk3k', 'rating': 1500}, 'isMyTurn': True, 'secondsLeft': 480, 'compat': {'bot': True, 'board': True}, 'id': '4bebkUSq'}}
2024-01-26 16:56:17,365 __main__ (lichess-bot.py:254) INFO --- Process Used. Count: 1. IDs: {'4bebkUSq'}
2024-01-26 16:56:17,371 urllib3.connectionpool (connectionpool.py:1052) DEBUG Starting new HTTPS connection (1): lichess.org:443
2024-01-26 16:56:17,516 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/4bebkUSq HTTP/1.1" 200 None
2024-01-26 16:56:17,517 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': '4bebkUSq', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284502377, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:17,564 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:17,564 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game 4bebkUSq has pid=?
2024-01-26 16:56:17,564 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/4bebkUSq/white Rapid vs luk3k (1500?) (4bebkUSq)
2024-01-26 16:56:17,564 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:17,565 urllib3.connectionpool (connectionpool.py:1052) DEBUG Starting new HTTPS connection (2): lichess.org:443
2024-01-26 16:56:17,712 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:17,713 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:17,746 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:17,747 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:17,747 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:17,747 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game 4bebkUSq
2024-01-26 16:56:17,748 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:17,749 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:17,750 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100788)>: Connection made
2024-01-26 16:56:17,750 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << uci
2024-01-26 16:56:17,751 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:17,869 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> id name Stockfish 16
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >>
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Debug Log File type string default
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Clear Hash type button
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Ponder type check default false
2024-01-26 16:56:17,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:17,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:17,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:17,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:17,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:17,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name Use NNUE type check default true
2024-01-26 16:56:17,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:17,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> uciok
2024-01-26 16:56:17,873 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << ucinewgame
2024-01-26 16:56:17,873 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << isready
2024-01-26 16:56:17,874 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> readyok
2024-01-26 16:56:17,875 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3
2024-01-26 16:56:17,875 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,875 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,875 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:17,875 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:17,875 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove g8f6
2024-01-26 16:56:17,876 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:17,876 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:17,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:17,877 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove d2d4
2024-01-26 16:56:17,877 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:17,877 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,877 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,877 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 12000 hashfull 0 tbhits 0 time 2 pv d7d5
2024-01-26 16:56:17,878 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 23000 hashfull 0 tbhits 0 time 2 pv d7d5
2024-01-26 16:56:17,878 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove d7d5
2024-01-26 16:56:17,878 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:17,878 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,878 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:17,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:17,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove g2g3
2024-01-26 16:56:17,879 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:17,879 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,880 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:17,880 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:17,880 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove c7c5
2024-01-26 16:56:17,880 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:17,880 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:17,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:17,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove d4c5
2024-01-26 16:56:17,881 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:17,882 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:17,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 187000 hashfull 0 tbhits 0 time 1 pv e7e5
2024-01-26 16:56:17,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove e7e5
2024-01-26 16:56:17,883 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:17,883 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,883 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,883 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:17,883 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:17,883 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:17,884 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:17,884 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:17,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:17,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove f8c5
2024-01-26 16:56:17,885 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:17,885 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << go depth 2
2024-01-26 16:56:17,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:17,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:17,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:17,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100788)>: >> bestmove c2c4
2024-01-26 16:56:17,886 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100788)>: << quit
2024-01-26 16:56:17,890 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100788)>: Process exited
2024-01-26 16:56:17,891 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100788)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:17,891 backoff (_common.py:105) INFO Backing off play_game(...) for 0.4s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:17,891 lib.lichess (lichess.py:63) DEBUG Backing off 0.4 seconds after 1 tries calling function <function play_game at 0x7f265698ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7f2656999b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f265699a920>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'playing': 'https://lichess.org/4bebkUSq/white', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7f265699a8f0>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7f265699bc40>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569dfd00>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569df9a0>, 'game_id': '4bebkUSq'}
2024-01-26 16:56:17,892 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:18,270 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:18,456 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/4bebkUSq HTTP/1.1" 200 None
2024-01-26 16:56:18,457 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': '4bebkUSq', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284502377, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:18,457 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:18,457 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game 4bebkUSq has pid=?
2024-01-26 16:56:18,457 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/4bebkUSq/white Rapid vs luk3k (1500?) (4bebkUSq)
2024-01-26 16:56:18,457 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:18,491 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:18,491 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:18,529 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:18,529 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:18,530 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:18,530 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game 4bebkUSq
2024-01-26 16:56:18,530 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:18,532 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:18,532 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100795)>: Connection made
2024-01-26 16:56:18,533 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << uci
2024-01-26 16:56:18,534 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:18,651 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> id name Stockfish 16
2024-01-26 16:56:18,651 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:18,651 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >>
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Debug Log File type string default
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Clear Hash type button
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Ponder type check default false
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:18,652 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:18,653 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:18,654 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name Use NNUE type check default true
2024-01-26 16:56:18,654 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:18,654 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> uciok
2024-01-26 16:56:18,654 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << ucinewgame
2024-01-26 16:56:18,655 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << isready
2024-01-26 16:56:18,656 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> readyok
2024-01-26 16:56:18,657 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3
2024-01-26 16:56:18,657 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,657 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,657 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:18,657 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:18,658 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove g8f6
2024-01-26 16:56:18,658 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:18,658 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,659 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,659 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:18,659 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:18,659 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove d2d4
2024-01-26 16:56:18,660 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:18,660 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,660 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,660 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:18,661 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:18,661 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove d7d5
2024-01-26 16:56:18,661 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:18,662 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,662 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,662 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:18,662 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:18,662 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove g2g3
2024-01-26 16:56:18,663 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:18,663 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,663 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,663 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:18,664 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:18,664 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove c7c5
2024-01-26 16:56:18,664 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:18,664 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,665 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,665 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:18,665 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:18,665 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove d4c5
2024-01-26 16:56:18,666 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:18,666 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,666 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,666 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:18,666 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 187000 hashfull 0 tbhits 0 time 1 pv e7e5
2024-01-26 16:56:18,667 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove e7e5
2024-01-26 16:56:18,667 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:18,667 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,668 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,668 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:18,668 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:18,668 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:18,669 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:18,669 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,669 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,669 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:18,669 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:18,669 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove f8c5
2024-01-26 16:56:18,670 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:18,670 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << go depth 2
2024-01-26 16:56:18,671 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:18,671 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:18,671 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:18,671 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100795)>: >> bestmove c2c4
2024-01-26 16:56:18,671 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100795)>: << quit
2024-01-26 16:56:18,676 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100795)>: Process exited
2024-01-26 16:56:18,676 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100795)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:18,676 backoff (_common.py:105) INFO Backing off play_game(...) for 1.8s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:18,677 lib.lichess (lichess.py:63) DEBUG Backing off 1.8 seconds after 2 tries calling function <function play_game at 0x7f265698ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7f2656999b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f265699a920>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'playing': 'https://lichess.org/4bebkUSq/white', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7f265699a8f0>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7f265699bc40>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569dfd00>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569df9a0>, 'game_id': '4bebkUSq'}
2024-01-26 16:56:18,677 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:20,494 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:20,676 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/4bebkUSq HTTP/1.1" 200 None
2024-01-26 16:56:20,677 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': '4bebkUSq', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284502377, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:20,677 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:20,677 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game 4bebkUSq has pid=?
2024-01-26 16:56:20,677 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/4bebkUSq/white Rapid vs luk3k (1500?) (4bebkUSq)
2024-01-26 16:56:20,678 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:20,712 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:20,712 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:20,746 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:20,747 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:20,747 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:20,747 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game 4bebkUSq
2024-01-26 16:56:20,748 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:20,749 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:20,749 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100802)>: Connection made
2024-01-26 16:56:20,750 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << uci
2024-01-26 16:56:20,751 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:20,865 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> id name Stockfish 16
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >>
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Debug Log File type string default
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Clear Hash type button
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Ponder type check default false
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:20,866 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:20,867 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:20,868 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:20,868 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name Use NNUE type check default true
2024-01-26 16:56:20,868 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:20,868 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> uciok
2024-01-26 16:56:20,868 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << ucinewgame
2024-01-26 16:56:20,869 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << isready
2024-01-26 16:56:20,870 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> readyok
2024-01-26 16:56:20,871 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3
2024-01-26 16:56:20,871 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:20,871 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:20,872 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove g8f6
2024-01-26 16:56:20,872 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:20,872 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,873 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,873 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:20,873 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:20,873 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove d2d4
2024-01-26 16:56:20,874 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:20,874 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,874 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,874 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:20,874 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:20,875 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove d7d5
2024-01-26 16:56:20,875 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:20,875 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:20,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:20,876 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove g2g3
2024-01-26 16:56:20,877 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:20,877 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,877 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,877 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:20,877 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:20,878 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove c7c5
2024-01-26 16:56:20,878 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:20,879 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:20,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:20,879 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove d4c5
2024-01-26 16:56:20,880 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:20,880 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,880 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 20000 hashfull 0 tbhits 0 time 2 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:20,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 93500 hashfull 0 tbhits 0 time 2 pv e7e5
2024-01-26 16:56:20,881 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove e7e5
2024-01-26 16:56:20,881 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:20,882 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:20,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:20,882 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:20,883 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:20,883 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,883 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:20,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:20,884 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove f8c5
2024-01-26 16:56:20,885 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:20,885 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << go depth 2
2024-01-26 16:56:20,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:20,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:20,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:20,885 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100802)>: >> bestmove c2c4
2024-01-26 16:56:20,886 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100802)>: << quit
2024-01-26 16:56:20,891 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100802)>: Process exited
2024-01-26 16:56:20,891 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100802)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:20,892 backoff (_common.py:105) INFO Backing off play_game(...) for 3.1s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:20,892 lib.lichess (lichess.py:63) DEBUG Backing off 3.1 seconds after 3 tries calling function <function play_game at 0x7f265698ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7f2656999b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f265699a920>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'playing': 'https://lichess.org/4bebkUSq/white', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7f265699a8f0>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7f265699bc40>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569dfd00>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569df9a0>, 'game_id': '4bebkUSq'}
2024-01-26 16:56:20,892 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:24,027 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:24,209 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/4bebkUSq HTTP/1.1" 200 None
2024-01-26 16:56:24,210 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': '4bebkUSq', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284502377, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:24,210 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:24,210 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game 4bebkUSq has pid=?
2024-01-26 16:56:24,211 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/4bebkUSq/white Rapid vs luk3k (1500?) (4bebkUSq)
2024-01-26 16:56:24,211 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:24,249 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:24,249 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:24,304 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:24,305 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:24,305 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:24,305 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game 4bebkUSq
2024-01-26 16:56:24,306 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:24,307 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:24,308 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100811)>: Connection made
2024-01-26 16:56:24,308 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << uci
2024-01-26 16:56:24,310 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> id name Stockfish 16
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >>
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Debug Log File type string default
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Clear Hash type button
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Ponder type check default false
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:24,429 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:24,430 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:24,431 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:24,431 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:24,431 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:24,431 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name Use NNUE type check default true
2024-01-26 16:56:24,431 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:24,431 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> uciok
2024-01-26 16:56:24,432 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << ucinewgame
2024-01-26 16:56:24,432 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << isready
2024-01-26 16:56:24,434 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> readyok
2024-01-26 16:56:24,434 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3
2024-01-26 16:56:24,434 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,434 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,435 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:24,435 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:24,435 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove g8f6
2024-01-26 16:56:24,436 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:24,436 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,436 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,436 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:24,436 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:24,436 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove d2d4
2024-01-26 16:56:24,437 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:24,437 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,438 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,438 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:24,438 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:24,438 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove d7d5
2024-01-26 16:56:24,439 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:24,439 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,439 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,439 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:24,439 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:24,439 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove g2g3
2024-01-26 16:56:24,440 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:24,440 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,441 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,441 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:24,441 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:24,441 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove c7c5
2024-01-26 16:56:24,442 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:24,442 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,442 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,442 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:24,442 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:24,442 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove d4c5
2024-01-26 16:56:24,443 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:24,443 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:24,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 187000 hashfull 0 tbhits 0 time 1 pv e7e5
2024-01-26 16:56:24,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove e7e5
2024-01-26 16:56:24,445 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:24,445 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,445 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,445 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:24,445 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:24,446 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:24,446 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:24,447 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,447 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,447 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:24,447 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:24,447 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove f8c5
2024-01-26 16:56:24,448 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:24,448 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << go depth 2
2024-01-26 16:56:24,448 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:24,449 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:24,449 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:24,449 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100811)>: >> bestmove c2c4
2024-01-26 16:56:24,449 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100811)>: << quit
2024-01-26 16:56:24,454 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100811)>: Process exited
2024-01-26 16:56:24,454 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100811)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:24,454 backoff (_common.py:105) INFO Backing off play_game(...) for 2.8s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:24,455 lib.lichess (lichess.py:63) DEBUG Backing off 2.8 seconds after 4 tries calling function <function play_game at 0x7f265698ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7f2656999b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f265699a920>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'playing': 'https://lichess.org/4bebkUSq/white', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7f265699a8f0>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7f265699bc40>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569dfd00>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569df9a0>, 'game_id': '4bebkUSq'}
2024-01-26 16:56:24,455 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:27,307 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:27,499 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/4bebkUSq HTTP/1.1" 200 None
2024-01-26 16:56:27,499 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': '4bebkUSq', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284502377, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:27,499 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:27,499 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game 4bebkUSq has pid=?
2024-01-26 16:56:27,500 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/4bebkUSq/white Rapid vs luk3k (1500?) (4bebkUSq)
2024-01-26 16:56:27,500 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:27,535 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:27,535 lib.conversation (conversation.py:83) INFO *** https://lichess.org/4bebkUSq/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:27,571 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/4bebkUSq/chat HTTP/1.1" 200 11
2024-01-26 16:56:27,571 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:27,572 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:27,572 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game 4bebkUSq
2024-01-26 16:56:27,572 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:27,573 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:27,574 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100820)>: Connection made
2024-01-26 16:56:27,574 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << uci
2024-01-26 16:56:27,576 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:27,695 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> id name Stockfish 16
2024-01-26 16:56:27,696 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:27,696 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >>
2024-01-26 16:56:27,696 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Debug Log File type string default
2024-01-26 16:56:27,696 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:27,696 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Clear Hash type button
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Ponder type check default false
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:27,697 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:27,698 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:27,699 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:27,699 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name Use NNUE type check default true
2024-01-26 16:56:27,699 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:27,699 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> uciok
2024-01-26 16:56:27,700 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << ucinewgame
2024-01-26 16:56:27,700 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << isready
2024-01-26 16:56:27,702 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> readyok
2024-01-26 16:56:27,702 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3
2024-01-26 16:56:27,702 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,702 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,702 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:27,702 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:27,703 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove g8f6
2024-01-26 16:56:27,703 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:27,703 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,704 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,704 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:27,704 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:27,704 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove d2d4
2024-01-26 16:56:27,704 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:27,705 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,705 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,705 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:27,705 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:27,705 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove d7d5
2024-01-26 16:56:27,706 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:27,706 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:27,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:27,707 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove g2g3
2024-01-26 16:56:27,707 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:27,707 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,707 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,707 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 17000 hashfull 0 tbhits 0 time 2 pv c7c5
2024-01-26 16:56:27,708 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 32500 hashfull 0 tbhits 0 time 2 pv c7c5
2024-01-26 16:56:27,708 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove c7c5
2024-01-26 16:56:27,708 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:27,708 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,708 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,708 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 18000 hashfull 0 tbhits 0 time 2 pv d4c5
2024-01-26 16:56:27,709 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 32500 hashfull 0 tbhits 0 time 2 pv d4c5
2024-01-26 16:56:27,709 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove d4c5
2024-01-26 16:56:27,709 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:27,709 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,709 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,709 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 20000 hashfull 0 tbhits 0 time 2 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:27,710 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 93500 hashfull 0 tbhits 0 time 2 pv e7e5
2024-01-26 16:56:27,710 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove e7e5
2024-01-26 16:56:27,710 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:27,710 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,710 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,711 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:27,711 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:27,711 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:27,711 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:27,711 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,712 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,712 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:27,712 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:27,712 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove f8c5
2024-01-26 16:56:27,712 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:27,712 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << go depth 2
2024-01-26 16:56:27,713 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:27,713 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:27,713 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:27,713 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100820)>: >> bestmove c2c4
2024-01-26 16:56:27,713 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100820)>: << quit
2024-01-26 16:56:27,717 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100820)>: Process exited
2024-01-26 16:56:27,718 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100820)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:27,718 backoff (_common.py:105) INFO Backing off play_game(...) for 5.4s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:27,718 lib.lichess (lichess.py:63) DEBUG Backing off 5.4 seconds after 5 tries calling function <function play_game at 0x7f265698ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7f2656999b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f265699a920>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'playing': 'https://lichess.org/4bebkUSq/white', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7f265699a8f0>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7f265699bc40>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569dfd00>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7f26569df9a0>, 'game_id': '4bebkUSq'}
2024-01-26 16:56:27,719 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:28,831 __main__ (lichess-bot.py:370) DEBUG Event: {'type': 'gameFinish', 'game': {'fullId': '4bebkUSq8GI3', 'gameId': '4bebkUSq', 'fen': 'rnbqkbnr/pppppppp/8/8/8/8/PPPPPPPP/RNBQKBNR w KQkq - 0 1', 'color': 'white', 'lastMove': '', 'source': 'friend', 'status': {'id': 25, 'name': 'aborted'}, 'variant': {'key': 'standard', 'name': 'Standard'}, 'speed': 'rapid', 'perf': 'rapid', 'rated': False, 'hasMoved': False, 'opponent': {'id': 'luk3k', 'username': 'luk3k', 'rating': 1500}, 'isMyTurn': False, 'secondsLeft': 480, 'compat': {'bot': True, 'board': True}, 'id': '4bebkUSq'}}
2024-01-26 16:56:33,167 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:33,237 __mp_main__ (lichess-bot.py:65) DEBUG Received SIGINT. Terminating client.
2024-01-26 16:56:33,281 __mp_main__ (lichess-bot.py:65) DEBUG Received SIGINT. Terminating client.
2024-01-26 16:56:33,378 __main__ (lichess-bot.py:1091) ERROR Quitting lichess_bot due to an error:
Traceback (most recent call last): Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1088, in <module> File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1088, in <module>
start_lichess_bot() start_lichess_bot()
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1027, in start_lichess_bot File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1044, in start_lichess_bot
with engine_wrapper.create_engine(CONFIG): start(li, user_profile, CONFIG, logging_level, args.logfile, auto_log_filename)
File "/usr/lib/python3.10/contextlib.py", line 135, in __enter__ File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 228, in start
return next(self.gen) lichess_bot_main(li,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 60, in create_engine File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 308, in lichess_bot_main
Engine = getHomemadeEngine(cfg.name) event = next_event(control_queue)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 589, in getHomemadeEngine File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 363, in next_event
engine: type[MinimalEngine] = getattr(strategies, name) if "type" not in event:
AttributeError: module 'lib.strategies' has no attribute 'ProbStockfish' TypeError: argument of type 'NoneType' is not iterable

545
lichess_bot/lichess_bot_auto_logs/recent.log
View File

@@ -1,17 +1,17 @@
2024-01-25 17:53:40,978 __main__ (lichess-bot.py:1023) INFO 2024-01-26 16:56:35,832 __main__ (lichess-bot.py:1023) INFO
. _/| . _/|
. // o\ . // o\
. || ._) lichess_bot 2024.1.21.1 . || ._) lichess_bot 2024.1.21.1
. //__\ . //__\
. )___( Play on Lichess with a bot . )___( Play on Lichess with a bot
2024-01-25 17:53:40,998 lib.config (config.py:261) DEBUG Config: 2024-01-26 16:56:35,853 lib.config (config.py:261) DEBUG Config:
token: logger token: logger
url: https://lichess.org/ url: https://lichess.org/
engine: engine:
dir: ../ dir: ./engines
name: ProbStockfish name: ProbStockfish
working_dir: ../ working_dir: ''
protocol: homemade protocol: homemade
ponder: true ponder: true
polyglot: polyglot:
@@ -137,14 +137,14 @@ matchmaking:
challenge_mode: random challenge_mode: random
challenge_filter: none challenge_filter: none
2024-01-25 17:53:40,998 lib.config (config.py:262) DEBUG ==================== 2024-01-26 16:56:35,853 lib.config (config.py:262) DEBUG ====================
2024-01-25 17:53:41,003 lib.config (config.py:261) DEBUG Config: 2024-01-26 16:56:35,858 lib.config (config.py:261) DEBUG Config:
token: logger token: logger
url: https://lichess.org/ url: https://lichess.org/
engine: engine:
dir: ../ dir: ./engines
name: ProbStockfish name: ProbStockfish
working_dir: ../ working_dir: /home/luke/projects/pp-project/chess-engine-pp/lichess_bot
protocol: homemade protocol: homemade
ponder: true ponder: true
polyglot: polyglot:
@@ -282,18 +282,523 @@ matchmaking:
overrides: {} overrides: {}
pgn_file_grouping: game pgn_file_grouping: game
2024-01-25 17:53:41,003 lib.config (config.py:262) DEBUG ==================== 2024-01-26 16:56:35,858 lib.config (config.py:262) DEBUG ====================
2024-01-25 17:53:41,003 __main__ (lichess-bot.py:1026) INFO Checking engine configuration ... 2024-01-26 16:56:35,858 __main__ (lichess-bot.py:1026) INFO Checking engine configuration ...
2024-01-25 17:53:41,006 __main__ (lichess-bot.py:1091) ERROR Quitting lichess_bot due to an error: 2024-01-26 16:56:35,908 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:35,908 __main__ (lichess-bot.py:1029) INFO Engine configuration OK
2024-01-26 16:56:35,910 urllib3.connectionpool (connectionpool.py:1052) DEBUG Starting new HTTPS connection (1): lichess.org:443
2024-01-26 16:56:36,075 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/token/test HTTP/1.1" 200 None
2024-01-26 16:56:36,126 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/account HTTP/1.1" 200 None
2024-01-26 16:56:36,126 __main__ (lichess-bot.py:1038) INFO Welcome probabilistic-bot!
2024-01-26 16:56:36,127 __main__ (lichess-bot.py:206) INFO You're now connected to https://lichess.org/ and awaiting challenges.
2024-01-26 16:56:36,382 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/account/playing HTTP/1.1" 200 17
2024-01-26 16:56:46,023 __main__ (lichess-bot.py:370) DEBUG Event: {'type': 'challenge', 'challenge': {'id': 'KGZF6I6j', 'url': 'https://lichess.org/KGZF6I6j', 'status': 'created', 'challenger': {'id': 'luk3k', 'name': 'luk3k', 'rating': 1500, 'title': None, 'provisional': True, 'online': True, 'lag': 4}, 'destUser': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'rating': 2000, 'title': 'BOT', 'provisional': True, 'online': True}, 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'rated': False, 'speed': 'rapid', 'timeControl': {'type': 'clock', 'limit': 480, 'increment': 3, 'show': '8+3'}, 'color': 'black', 'finalColor': 'black', 'perf': {'icon': '\ue017', 'name': 'Rapid'}}, 'compat': {'bot': True, 'board': True}}
2024-01-26 16:56:46,024 __main__ (lichess-bot.py:421) INFO Accept Rapid casual challenge from luk3k (1500?) (KGZF6I6j)
2024-01-26 16:56:46,066 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/challenge/KGZF6I6j/accept HTTP/1.1" 200 11
2024-01-26 16:56:46,066 __main__ (lichess-bot.py:254) INFO --- Process Queued. Count: 1. IDs: {'KGZF6I6j'}
2024-01-26 16:56:46,067 __main__ (lichess-bot.py:370) DEBUG Event: {'type': 'gameStart', 'game': {'fullId': 'KGZF6I6jogOQ', 'gameId': 'KGZF6I6j', 'fen': 'rnbqkbnr/pppppppp/8/8/8/8/PPPPPPPP/RNBQKBNR w KQkq - 0 1', 'color': 'white', 'lastMove': '', 'source': 'friend', 'status': {'id': 20, 'name': 'started'}, 'variant': {'key': 'standard', 'name': 'Standard'}, 'speed': 'rapid', 'perf': 'rapid', 'rated': False, 'hasMoved': False, 'opponent': {'id': 'luk3k', 'username': 'luk3k', 'rating': 1500}, 'isMyTurn': True, 'secondsLeft': 480, 'compat': {'bot': True, 'board': True}, 'id': 'KGZF6I6j'}}
2024-01-26 16:56:46,067 __main__ (lichess-bot.py:254) INFO --- Process Used. Count: 1. IDs: {'KGZF6I6j'}
2024-01-26 16:56:46,073 urllib3.connectionpool (connectionpool.py:1052) DEBUG Starting new HTTPS connection (1): lichess.org:443
2024-01-26 16:56:46,228 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/KGZF6I6j HTTP/1.1" 200 None
2024-01-26 16:56:46,228 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': 'KGZF6I6j', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284606050, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:46,276 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:46,276 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game KGZF6I6j has pid=?
2024-01-26 16:56:46,277 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/KGZF6I6j/white Rapid vs luk3k (1500?) (KGZF6I6j)
2024-01-26 16:56:46,277 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:46,278 urllib3.connectionpool (connectionpool.py:1052) DEBUG Starting new HTTPS connection (2): lichess.org:443
2024-01-26 16:56:46,428 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:46,429 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:46,462 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:46,463 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:46,463 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:46,463 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game KGZF6I6j
2024-01-26 16:56:46,464 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:46,466 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:46,466 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100959)>: Connection made
2024-01-26 16:56:46,466 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << uci
2024-01-26 16:56:46,467 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:46,585 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> id name Stockfish 16
2024-01-26 16:56:46,585 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:46,585 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >>
2024-01-26 16:56:46,585 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Debug Log File type string default
2024-01-26 16:56:46,586 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:46,586 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:46,586 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Clear Hash type button
2024-01-26 16:56:46,586 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Ponder type check default false
2024-01-26 16:56:46,586 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:46,586 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:46,587 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:46,587 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:46,587 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:46,587 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:46,587 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:46,587 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:46,588 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:46,588 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:46,588 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:46,588 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:46,588 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:46,588 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:46,589 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name Use NNUE type check default true
2024-01-26 16:56:46,589 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:46,589 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> uciok
2024-01-26 16:56:46,590 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << ucinewgame
2024-01-26 16:56:46,590 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << isready
2024-01-26 16:56:46,592 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> readyok
2024-01-26 16:56:46,592 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3
2024-01-26 16:56:46,592 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,593 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,593 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:46,593 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:46,593 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove g8f6
2024-01-26 16:56:46,594 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:46,594 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,595 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,595 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:46,595 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:46,595 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove d2d4
2024-01-26 16:56:46,596 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:46,596 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,596 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,596 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:46,596 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:46,597 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove d7d5
2024-01-26 16:56:46,597 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:46,598 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,598 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,598 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:46,598 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:46,598 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove g2g3
2024-01-26 16:56:46,599 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:46,599 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,599 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,599 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 17000 hashfull 0 tbhits 0 time 2 pv c7c5
2024-01-26 16:56:46,600 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 32500 hashfull 0 tbhits 0 time 2 pv c7c5
2024-01-26 16:56:46,600 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove c7c5
2024-01-26 16:56:46,601 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:46,601 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,601 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,601 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:46,601 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:46,601 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove d4c5
2024-01-26 16:56:46,602 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:46,602 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,603 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,603 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:46,603 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 187000 hashfull 0 tbhits 0 time 1 pv e7e5
2024-01-26 16:56:46,603 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove e7e5
2024-01-26 16:56:46,604 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:46,604 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,604 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,604 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 25000 hashfull 0 tbhits 0 time 2 pv f1g2 f8c5
2024-01-26 16:56:46,605 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 43500 hashfull 0 tbhits 0 time 2 pv f1g2 f8c5
2024-01-26 16:56:46,605 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:46,606 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:46,606 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,606 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,606 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:46,606 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:46,606 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove f8c5
2024-01-26 16:56:46,607 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:46,607 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << go depth 2
2024-01-26 16:56:46,608 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:46,608 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:46,608 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:46,608 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100959)>: >> bestmove c2c4
2024-01-26 16:56:46,609 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100959)>: << quit
2024-01-26 16:56:46,613 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100959)>: Process exited
2024-01-26 16:56:46,613 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100959)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:46,614 backoff (_common.py:105) INFO Backing off play_game(...) for 1.0s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:46,614 lib.lichess (lichess.py:63) DEBUG Backing off 1.0 seconds after 1 tries calling function <function play_game at 0x7ffb25b9ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7ffb25ba9b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25bef9a0>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 0, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7ffb25befd60>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7ffb25bef9d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25befcd0>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25beff70>, 'game_id': 'KGZF6I6j'}
2024-01-26 16:56:46,615 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:47,602 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:47,785 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/KGZF6I6j HTTP/1.1" 200 None
2024-01-26 16:56:47,786 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': 'KGZF6I6j', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284606050, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:47,786 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:47,786 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game KGZF6I6j has pid=?
2024-01-26 16:56:47,786 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/KGZF6I6j/white Rapid vs luk3k (1500?) (KGZF6I6j)
2024-01-26 16:56:47,787 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:47,822 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:47,823 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:47,858 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:47,858 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:47,859 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:47,859 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game KGZF6I6j
2024-01-26 16:56:47,859 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:47,861 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:47,861 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100966)>: Connection made
2024-01-26 16:56:47,861 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << uci
2024-01-26 16:56:47,863 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:47,981 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> id name Stockfish 16
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >>
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Debug Log File type string default
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Clear Hash type button
2024-01-26 16:56:47,982 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Ponder type check default false
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:47,983 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:47,984 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:47,984 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:47,984 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:47,984 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name Use NNUE type check default true
2024-01-26 16:56:47,984 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:47,984 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> uciok
2024-01-26 16:56:47,985 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << ucinewgame
2024-01-26 16:56:47,985 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << isready
2024-01-26 16:56:47,987 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> readyok
2024-01-26 16:56:47,987 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3
2024-01-26 16:56:47,987 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:47,988 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:47,988 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:47,988 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:47,988 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove g8f6
2024-01-26 16:56:47,989 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:47,989 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:47,990 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:47,990 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:47,990 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:47,990 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove d2d4
2024-01-26 16:56:47,991 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:47,991 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:47,992 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:47,992 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:47,992 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:47,992 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove d7d5
2024-01-26 16:56:47,993 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:47,994 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:47,994 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:47,994 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:47,994 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:47,994 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove g2g3
2024-01-26 16:56:47,995 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:47,995 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:47,996 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:47,996 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:47,996 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:47,996 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove c7c5
2024-01-26 16:56:47,997 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:47,997 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:47,998 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:47,998 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:47,998 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:47,998 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove d4c5
2024-01-26 16:56:47,999 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:47,999 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:48,000 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,000 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:48,000 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 187000 hashfull 0 tbhits 0 time 1 pv e7e5
2024-01-26 16:56:48,000 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove e7e5
2024-01-26 16:56:48,001 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:48,001 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:48,001 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,001 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:48,001 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 43500 hashfull 0 tbhits 0 time 2 pv f1g2 f8c5
2024-01-26 16:56:48,002 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:48,002 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:48,002 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:48,003 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,003 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:48,003 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:48,003 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove f8c5
2024-01-26 16:56:48,004 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:48,004 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << go depth 2
2024-01-26 16:56:48,004 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,004 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:48,004 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:48,005 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100966)>: >> bestmove c2c4
2024-01-26 16:56:48,005 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100966)>: << quit
2024-01-26 16:56:48,009 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100966)>: Process exited
2024-01-26 16:56:48,009 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100966)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:48,010 backoff (_common.py:105) INFO Backing off play_game(...) for 0.4s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:48,010 lib.lichess (lichess.py:63) DEBUG Backing off 0.4 seconds after 2 tries calling function <function play_game at 0x7ffb25b9ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7ffb25ba9b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25bef9a0>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 0, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7ffb25befd60>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7ffb25bef9d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25befcd0>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25beff70>, 'game_id': 'KGZF6I6j'}
2024-01-26 16:56:48,010 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:48,380 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:48,608 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/KGZF6I6j HTTP/1.1" 200 None
2024-01-26 16:56:48,608 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': 'KGZF6I6j', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284606050, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:48,608 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:48,609 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game KGZF6I6j has pid=?
2024-01-26 16:56:48,609 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/KGZF6I6j/white Rapid vs luk3k (1500?) (KGZF6I6j)
2024-01-26 16:56:48,609 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:48,646 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:48,646 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:48,681 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:48,682 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:48,682 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:48,682 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game KGZF6I6j
2024-01-26 16:56:48,682 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:48,684 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:48,684 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100973)>: Connection made
2024-01-26 16:56:48,685 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << uci
2024-01-26 16:56:48,686 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:48,804 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> id name Stockfish 16
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >>
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Debug Log File type string default
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Clear Hash type button
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Ponder type check default false
2024-01-26 16:56:48,805 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:48,806 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name Use NNUE type check default true
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:48,807 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> uciok
2024-01-26 16:56:48,808 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << ucinewgame
2024-01-26 16:56:48,808 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << isready
2024-01-26 16:56:48,810 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> readyok
2024-01-26 16:56:48,810 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3
2024-01-26 16:56:48,810 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,811 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,811 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:48,811 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:48,811 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove g8f6
2024-01-26 16:56:48,812 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:48,812 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,812 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,813 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 10000 hashfull 0 tbhits 0 time 2 pv d2d4
2024-01-26 16:56:48,813 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 20000 hashfull 0 tbhits 0 time 2 pv d2d4
2024-01-26 16:56:48,813 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove d2d4
2024-01-26 16:56:48,814 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:48,814 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,814 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,814 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:48,814 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:48,814 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove d7d5
2024-01-26 16:56:48,815 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:48,815 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,816 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,816 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:48,816 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:48,816 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove g2g3
2024-01-26 16:56:48,817 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:48,817 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,817 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,818 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:48,818 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:48,818 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove c7c5
2024-01-26 16:56:48,819 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:48,819 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,819 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,820 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 18000 hashfull 0 tbhits 0 time 2 pv d4c5
2024-01-26 16:56:48,820 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 32500 hashfull 0 tbhits 0 time 2 pv d4c5
2024-01-26 16:56:48,820 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove d4c5
2024-01-26 16:56:48,821 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:48,821 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,821 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,821 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:48,821 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 187000 hashfull 0 tbhits 0 time 1 pv e7e5
2024-01-26 16:56:48,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove e7e5
2024-01-26 16:56:48,822 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:48,823 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,823 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,823 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:48,823 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv f1g2 f8c5
2024-01-26 16:56:48,823 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:48,824 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:48,824 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:48,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:48,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove f8c5
2024-01-26 16:56:48,826 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:48,826 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << go depth 2
2024-01-26 16:56:48,826 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:48,827 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:48,827 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:48,827 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100973)>: >> bestmove c2c4
2024-01-26 16:56:48,827 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100973)>: << quit
2024-01-26 16:56:48,832 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100973)>: Process exited
2024-01-26 16:56:48,832 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100973)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:48,833 backoff (_common.py:105) INFO Backing off play_game(...) for 3.8s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:48,833 lib.lichess (lichess.py:63) DEBUG Backing off 3.8 seconds after 3 tries calling function <function play_game at 0x7ffb25b9ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7ffb25ba9b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25bef9a0>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 0, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7ffb25befd60>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7ffb25bef9d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25befcd0>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25beff70>, 'game_id': 'KGZF6I6j'}
2024-01-26 16:56:48,833 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:52,679 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-26 16:56:52,864 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/KGZF6I6j HTTP/1.1" 200 None
2024-01-26 16:56:52,864 __mp_main__ (lichess-bot.py:572) DEBUG Initial state: {'id': 'KGZF6I6j', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'rapid', 'perf': {'name': 'Rapid'}, 'rated': False, 'createdAt': 1706284606050, 'white': {'id': 'probabilistic-bot', 'name': 'probabilistic-bot', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'luk3k', 'name': 'luk3k', 'title': None, 'rating': 1500, 'provisional': True}, 'initialFen': 'startpos', 'clock': {'initial': 480000, 'increment': 3000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 480000, 'btime': 480000, 'winc': 3000, 'binc': 3000, 'status': 'started'}}
2024-01-26 16:56:52,864 lib.engine_wrapper (engine_wrapper.py:65) DEBUG Starting engine: ['/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/engines/ProbStockfish']
2024-01-26 16:56:52,865 __mp_main__ (lichess-bot.py:578) DEBUG The engine for game KGZF6I6j has pid=?
2024-01-26 16:56:52,865 __mp_main__ (lichess-bot.py:581) INFO +++ https://lichess.org/KGZF6I6j/white Rapid vs luk3k (1500?) (KGZF6I6j)
2024-01-26 16:56:52,865 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [player] probabilistic-bot: Hi! I'm probabilistic-bot. Good luck! Type !help for a list of commands I can respond to.
2024-01-26 16:56:52,900 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:52,901 lib.conversation (conversation.py:83) INFO *** https://lichess.org/KGZF6I6j/white [spectator] probabilistic-bot: Hi! I'm probabilistic-bot. Type !help for a list of commands I can respond to.
2024-01-26 16:56:52,937 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/KGZF6I6j/chat HTTP/1.1" 200 11
2024-01-26 16:56:52,937 __mp_main__ (lichess-bot.py:688) INFO
2024-01-26 16:56:52,937 __mp_main__ (lichess-bot.py:689) INFO move: 1
2024-01-26 16:56:52,938 lib.engine_wrapper (engine_wrapper.py:650) INFO Searching for time 10 seconds for game KGZF6I6j
2024-01-26 16:56:52,938 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-26 16:56:52,939 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-26 16:56:52,940 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=100981)>: Connection made
2024-01-26 16:56:52,940 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << uci
2024-01-26 16:56:52,942 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> Stockfish 16 by the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:53,057 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> id name Stockfish 16
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> id author the Stockfish developers (see AUTHORS file)
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >>
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Debug Log File type string default
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Threads type spin default 1 min 1 max 1024
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Hash type spin default 16 min 1 max 33554432
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Clear Hash type button
2024-01-26 16:56:53,058 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Ponder type check default false
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name MultiPV type spin default 1 min 1 max 500
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Skill Level type spin default 20 min 0 max 20
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Slow Mover type spin default 100 min 10 max 1000
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name nodestime type spin default 0 min 0 max 10000
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name UCI_Chess960 type check default false
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name UCI_AnalyseMode type check default false
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name UCI_LimitStrength type check default false
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name UCI_Elo type spin default 1320 min 1320 max 3190
2024-01-26 16:56:53,059 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name UCI_ShowWDL type check default false
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name SyzygyPath type string default <empty>
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name SyzygyProbeDepth type spin default 1 min 1 max 100
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Syzygy50MoveRule type check default true
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name SyzygyProbeLimit type spin default 7 min 0 max 7
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name Use NNUE type check default true
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> option name EvalFile type string default nn-5af11540bbfe.nnue
2024-01-26 16:56:53,060 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> uciok
2024-01-26 16:56:53,061 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << ucinewgame
2024-01-26 16:56:53,061 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << isready
2024-01-26 16:56:53,063 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> readyok
2024-01-26 16:56:53,063 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3
2024-01-26 16:56:53,063 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,063 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,063 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -5 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:53,063 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -5 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv g8f6
2024-01-26 16:56:53,063 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove g8f6
2024-01-26 16:56:53,064 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6
2024-01-26 16:56:53,064 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,064 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,064 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -28 nodes 20 nps 20000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:53,064 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -28 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d2d4
2024-01-26 16:56:53,064 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove d2d4
2024-01-26 16:56:53,065 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4
2024-01-26 16:56:53,065 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,065 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,065 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -9 nodes 24 nps 24000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:53,065 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -9 nodes 46 nps 46000 hashfull 0 tbhits 0 time 1 pv d7d5
2024-01-26 16:56:53,065 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove d7d5
2024-01-26 16:56:53,066 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5
2024-01-26 16:56:53,066 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,066 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,066 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -59 nodes 48 nps 48000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:53,066 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -59 nodes 78 nps 78000 hashfull 0 tbhits 0 time 1 pv g2g3
2024-01-26 16:56:53,066 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove g2g3
2024-01-26 16:56:53,067 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3
2024-01-26 16:56:53,067 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,067 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,067 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp 13 nodes 34 nps 34000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:53,067 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp 13 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv c7c5
2024-01-26 16:56:53,067 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove c7c5
2024-01-26 16:56:53,068 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5
2024-01-26 16:56:53,068 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,068 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,068 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -18 nodes 36 nps 36000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:53,068 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -18 nodes 65 nps 65000 hashfull 0 tbhits 0 time 1 pv d4c5
2024-01-26 16:56:53,068 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove d4c5
2024-01-26 16:56:53,069 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5
2024-01-26 16:56:53,069 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,069 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,069 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp 525 nodes 40 nps 40000 hashfull 0 tbhits 0 time 1 pv d8a5 b2b4 a5b4 b1c3 b4c3 c1d2
2024-01-26 16:56:53,069 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp 6 nodes 187 nps 93500 hashfull 0 tbhits 0 time 2 pv e7e5
2024-01-26 16:56:53,069 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove e7e5
2024-01-26 16:56:53,070 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5
2024-01-26 16:56:53,070 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,070 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,070 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -78 nodes 50 nps 25000 hashfull 0 tbhits 0 time 2 pv f1g2 f8c5
2024-01-26 16:56:53,071 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -78 nodes 87 nps 43500 hashfull 0 tbhits 0 time 2 pv f1g2 f8c5
2024-01-26 16:56:53,071 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove f1g2 ponder f8c5
2024-01-26 16:56:53,071 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2
2024-01-26 16:56:53,071 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,072 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,072 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp 78 nodes 38 nps 38000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:53,072 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp 78 nodes 74 nps 74000 hashfull 0 tbhits 0 time 1 pv f8c5
2024-01-26 16:56:53,072 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove f8c5
2024-01-26 16:56:53,072 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << position startpos moves g1h3 g8f6 d2d4 d7d5 g2g3 c7c5 d4c5 e7e5 f1g2 f8c5
2024-01-26 16:56:53,072 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << go depth 2
2024-01-26 16:56:53,073 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info string NNUE evaluation using nn-5af11540bbfe.nnue enabled
2024-01-26 16:56:53,073 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 1 seldepth 1 multipv 1 score cp -80 nodes 50 nps 50000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:53,073 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> info depth 2 seldepth 2 multipv 1 score cp -80 nodes 87 nps 87000 hashfull 0 tbhits 0 time 1 pv c2c4
2024-01-26 16:56:53,073 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=100981)>: >> bestmove c2c4
2024-01-26 16:56:53,073 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=100981)>: << quit
2024-01-26 16:56:53,077 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=100981)>: Process exited
2024-01-26 16:56:53,078 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=100981)>: Connection lost (exit code: 0, error: None)
2024-01-26 16:56:53,078 backoff (_common.py:105) INFO Backing off play_game(...) for 0.8s (TypeError: cannot unpack non-iterable NoneType object)
2024-01-26 16:56:53,078 lib.lichess (lichess.py:63) DEBUG Backing off 0.8 seconds after 4 tries calling function <function play_game at 0x7ffb25b9ed40> with args () and kwargs {'li': <lib.lichess.Lichess object at 0x7ffb25ba9b40>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25bef9a0>, 'user_profile': {'id': 'probabilistic-bot', 'username': 'probabilistic-bot', 'perfs': {'blitz': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'bullet': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'rapid': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}}, 'title': 'BOT', 'createdAt': 1706196643794, 'seenAt': 1706284268775, 'playTime': {'total': 1252, 'tv': 0}, 'url': 'https://lichess.org/@/probabilistic-bot', 'count': {'all': 5, 'rated': 0, 'ai': 0, 'draw': 0, 'drawH': 0, 'loss': 4, 'lossH': 4, 'win': 1, 'winH': 1, 'bookmark': 0, 'playing': 0, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <lib.config.Configuration object at 0x7ffb25befd60>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x7ffb25bef9d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25befcd0>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x7ffb25beff70>, 'game_id': 'KGZF6I6j'}
2024-01-26 16:56:53,078 lib.lichess (lichess.py:65) DEBUG Exception: Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/.venv/lib/python3.10/site-packages/backoff/_sync.py", line 105, in retry
ret = target(*args, **kwargs)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 620, in play_game
engine.play_move(board,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 157, in play_move
best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/strategies.py", line 105, in search
mean, std = engine.simulate_game(board.copy(), move, 10)
TypeError: cannot unpack non-iterable NoneType object
2024-01-26 16:56:53,636 __mp_main__ (lichess-bot.py:65) DEBUG Received SIGINT. Terminating client.
2024-01-26 16:56:53,636 __mp_main__ (lichess-bot.py:65) DEBUG Received SIGINT. Terminating client.
2024-01-26 16:56:54,074 __main__ (lichess-bot.py:1091) ERROR Quitting lichess_bot due to an error:
Traceback (most recent call last): Traceback (most recent call last):
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1088, in <module> File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1088, in <module>
start_lichess_bot() start_lichess_bot()
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1027, in start_lichess_bot File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 1044, in start_lichess_bot
with engine_wrapper.create_engine(CONFIG): start(li, user_profile, CONFIG, logging_level, args.logfile, auto_log_filename)
File "/usr/lib/python3.10/contextlib.py", line 135, in __enter__ File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 228, in start
return next(self.gen) lichess_bot_main(li,
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 60, in create_engine File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 308, in lichess_bot_main
Engine = getHomemadeEngine(cfg.name) event = next_event(control_queue)
File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lib/engine_wrapper.py", line 589, in getHomemadeEngine File "/home/luke/projects/pp-project/chess-engine-pp/lichess_bot/lichess-bot.py", line 363, in next_event
engine: type[MinimalEngine] = getattr(strategies, name) if "type" not in event:
AttributeError: module 'lib.strategies' has no attribute 'ProbStockfish' TypeError: argument of type 'NoneType' is not iterable