I am writing an engine with pondering. When I turn on pondering, I receive the error <

<div class="snippet-clipboard-content notranslate position-relative overflow-auto" data-snippet-clip

What is the meaning of chess.engine.EngineTerminatedError: engine event loop dead?,about lichess-bot-devs/lichess-bot

Comments (7)

MarkZH commented on June 2, 2024

Please post an entire log, from startup to the error. There should be logs in the lichess_bot_auto_logs folder in your lichess-bot directory. Or, you can run python lichess-bot -v -l log.txt (-v for verbose debuging output and -l log.txt to specify the log file).

from lichess-bot.

glolichen commented on June 2, 2024

2024-01-30 17:16:29,435 __main__ (lichess-bot.py:965) INFO 
    .   _/|
    .  // o\
    .  || ._)  lichess-bot 2023.8.2.2
    .  //__\
    .  )___(   Play on Lichess with a bot
    
2024-01-30 17:16:29,446 config (config.py:242) DEBUG Config:
token: logger
url: https://lichess.org/
engine:
  dir: /home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/
  name: potato-chess-uci
  working_dir: ''
  protocol: uci
  ponder: true
  polyglot:
    enabled: false
    book:
      standard:
      - engines/book1.bin
      - engines/book2.bin
    min_weight: 1
    selection: weighted_random
    max_depth: 20
  draw_or_resign:
    resign_enabled: false
    resign_score: -1000
    resign_for_egtb_minus_two: true
    resign_moves: 3
    offer_draw_enabled: true
    offer_draw_score: 0
    offer_draw_for_egtb_zero: true
    offer_draw_moves: 10
    offer_draw_pieces: 10
  online_moves:
    max_out_of_book_moves: 10
    max_retries: 2
    chessdb_book:
      enabled: false
      min_time: 20
      move_quality: good
      min_depth: 20
    lichess_cloud_analysis:
      enabled: false
      min_time: 20
      move_quality: best
      max_score_difference: 50
      min_depth: 20
      min_knodes: 0
    lichess_opening_explorer:
      enabled: false
      min_time: 20
      source: masters
      player_name: ''
      sort: winrate
      min_games: 10
    online_egtb:
      enabled: true
      min_time: 20
      max_pieces: 7
      source: lichess
      move_quality: best
  lichess_bot_tbs:
    syzygy:
      enabled: false
      paths:
      - engines/syzygy
      max_pieces: 7
      move_quality: best
    gaviota:
      enabled: false
      paths:
      - engines/gaviota
      max_pieces: 5
      min_dtm_to_consider_as_wdl_1: 120
      move_quality: best
  homemade_options: null
  uci_options:
    Move Overhead: 190
    go_commands: null
  silence_stderr: false
abort_time: 30
fake_think_time: false
rate_limiting_delay: 0
move_overhead: 150
correspondence:
  move_time: 60
  checkin_period: 300
  disconnect_time: 150
  ponder: true
challenge:
  concurrency: 1
  sort_by: best
  accept_bot: true
  only_bot: false
  max_increment: 20
  min_increment: 0
  max_base: 1800
  min_base: 0
  max_days: 14
  min_days: 1
  variants:
  - standard
  time_controls:
  - bullet
  - blitz
  - rapid
  - classical
  modes:
  - casual
  - rated
  bullet_requires_increment: false
greeting:
  goodbye: Good game, {opponent}!
  goodbye_spectators: Thanks for watching!
matchmaking:
  allow_matchmaking: false
  challenge_variant: random
  challenge_timeout: 30
  challenge_initial_time:
  - 60
  - 180
  challenge_increment:
  - 1
  - 2
  opponent_rating_difference: 300
  opponent_allow_tos_violation: false
  challenge_mode: random
  challenge_filter: none

2024-01-30 17:16:29,446 config (config.py:243) DEBUG ====================
2024-01-30 17:16:29,449 config (config.py:242) DEBUG Config:
token: logger
url: https://lichess.org/
engine:
  dir: /home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/
  name: potato-chess-uci
  working_dir: /home/jayden/Desktop/Programs/potato-chess/lichess-bot
  protocol: uci
  ponder: true
  polyglot:
    enabled: false
    book:
      standard:
      - engines/book1.bin
      - engines/book2.bin
    min_weight: 1
    selection: weighted_random
    max_depth: 20
  draw_or_resign:
    resign_enabled: false
    resign_score: -1000
    resign_for_egtb_minus_two: true
    resign_moves: 3
    offer_draw_enabled: true
    offer_draw_score: 0
    offer_draw_for_egtb_zero: true
    offer_draw_moves: 10
    offer_draw_pieces: 10
  online_moves:
    max_out_of_book_moves: 10
    max_retries: 2
    chessdb_book:
      enabled: false
      min_time: 20
      move_quality: good
      min_depth: 20
    lichess_cloud_analysis:
      enabled: false
      min_time: 20
      move_quality: best
      max_score_difference: 50
      min_depth: 20
      min_knodes: 0
    lichess_opening_explorer:
      enabled: false
      min_time: 20
      source: masters
      player_name: ''
      sort: winrate
      min_games: 10
    online_egtb:
      enabled: true
      min_time: 20
      max_pieces: 7
      source: lichess
      move_quality: best
  lichess_bot_tbs:
    syzygy:
      enabled: false
      paths:
      - engines/syzygy
      max_pieces: 7
      move_quality: best
    gaviota:
      enabled: false
      paths:
      - engines/gaviota
      max_pieces: 5
      min_dtm_to_consider_as_wdl_1: 120
      move_quality: best
  homemade_options: null
  uci_options:
    Move Overhead: 190
    go_commands: null
  silence_stderr: false
  uci_ponder: false
abort_time: 30
fake_think_time: false
rate_limiting_delay: 0
move_overhead: 150
correspondence:
  move_time: 60
  checkin_period: 300
  disconnect_time: 150
  ponder: true
  uci_ponder: false
challenge:
  concurrency: 1
  sort_by: best
  accept_bot: true
  only_bot: false
  max_increment: 20
  min_increment: 0
  max_base: 1800
  min_base: 0
  max_days: 14
  min_days: 1
  variants:
  - standard
  time_controls:
  - bullet
  - blitz
  - rapid
  - classical
  modes:
  - casual
  - rated
  bullet_requires_increment: false
  block_list: []
  allow_list: []
greeting:
  goodbye: Good game, {opponent}!
  goodbye_spectators: Thanks for watching!
  hello: ''
  hello_spectators: ''
matchmaking:
  allow_matchmaking: false
  challenge_variant: random
  challenge_timeout: 30
  challenge_initial_time:
  - 60
  - 180
  challenge_increment:
  - 1
  - 2
  opponent_rating_difference: 300
  opponent_allow_tos_violation: false
  challenge_mode: random
  challenge_filter: none
  block_list: []
  challenge_days:
  - null
  opponent_min_rating: 600
  opponent_max_rating: 4000

2024-01-30 17:16:29,449 config (config.py:243) DEBUG ====================
2024-01-30 17:16:29,450 urllib3.connectionpool (connectionpool.py:1048) DEBUG Starting new HTTPS connection (1): lichess.org:443
2024-01-30 17:16:30,117 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/token/test HTTP/1.1" 200 None
2024-01-30 17:16:30,340 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/account HTTP/1.1" 200 None
2024-01-30 17:16:30,340 __main__ (lichess-bot.py:974) INFO Welcome potato-chess-bot!
2024-01-30 17:16:30,342 __main__ (lichess-bot.py:217) INFO You're now connected to https://lichess.org/ and awaiting challenges.
2024-01-30 17:16:30,679 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/account/playing HTTP/1.1" 200 None
2024-01-30 17:16:31,356 __main__ (lichess-bot.py:381) DEBUG Event: {'type': 'gameStart', 'game': {'fullId': 'ZtDrfFHQWYaX', 'gameId': 'ZtDrfFHQ', 'fen': 'r2q2k1/p1p2ppp/3n4/3prb2/8/P1PQB3/4PPPP/3RKB1R w K - 6 17', 'color': 'white', 'lastMove': 'c8f5', 'source': 'friend', 'status': {'id': 20, 'name': 'started'}, 'variant': {'key': 'standard', 'name': 'Standard'}, 'speed': 'blitz', 'perf': 'blitz', 'rated': True, 'hasMoved': True, 'opponent': {'id': 'flounderbot', 'username': 'BOT FlounderBot', 'rating': 1777}, 'isMyTurn': True, 'secondsLeft': 100, 'compat': {'bot': True, 'board': True}, 'id': 'ZtDrfFHQ'}}
2024-01-30 17:16:31,356 __main__ (lichess-bot.py:264) INFO --- Process Used. Count: 1. IDs: {'ZtDrfFHQ'}
2024-01-30 17:16:31,361 urllib3.connectionpool (connectionpool.py:1048) DEBUG Starting new HTTPS connection (1): lichess.org:443
2024-01-30 17:16:32,072 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:16:32,073 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5', 'wtime': 99430, 'btime': 122350, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:16:32,074 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:16:32,074 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:16:32,075 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:16:32,076 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=141378)>: Connection made
2024-01-30 17:16:32,076 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << uci
2024-01-30 17:16:32,078 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> id name Potato Chess
2024-01-30 17:16:32,078 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> id author Jayden Li
2024-01-30 17:16:32,078 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:16:32,078 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> uciok
2024-01-30 17:16:32,078 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << setoption name Move Overhead value 190
2024-01-30 17:16:32,079 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=141378
2024-01-30 17:16:32,079 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:16:32,080 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:16:32,080 __mp_main__ (lichess-bot.py:685) INFO move: 17
2024-01-30 17:16:32,080 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 99280 btime 122350 for game ZtDrfFHQ
2024-01-30 17:16:32,080 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << ucinewgame
2024-01-30 17:16:32,080 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << isready
2024-01-30 17:16:32,080 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> readyok
2024-01-30 17:16:32,081 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5
2024-01-30 17:16:32,081 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 99280 btime 122350 winc 2000 binc 2000
2024-01-30 17:16:36,903 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 28097 currmove d3d4 score cp 9
2024-01-30 17:16:36,904 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 123509 currmove d3d4 score cp 4
2024-01-30 17:16:36,904 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 349843 currmove d3d4 score cp 0
2024-01-30 17:16:36,904 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 1933756 currmove d3d4 score cp 6
2024-01-30 17:16:36,904 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 5159878 currmove d3d4 score cp 6
2024-01-30 17:16:36,904 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d3d4
2024-01-30 17:16:36,905 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: 0.06
2024-01-30 17:16:36,905 engine_wrapper (engine_wrapper.py:283) INFO Depth: 7
2024-01-30 17:16:36,905 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 5.2M
2024-01-30 17:16:36,906 urllib3.connectionpool (connectionpool.py:1048) DEBUG Starting new HTTPS connection (2): lichess.org:443
2024-01-30 17:16:37,595 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d3d4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:16:37,597 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4', 'wtime': 95910, 'btime': 122350, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:16:40,002 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4', 'wtime': 95910, 'btime': 121940, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:16:40,003 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:16:40,003 __mp_main__ (lichess-bot.py:685) INFO move: 18
2024-01-30 17:16:40,003 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 95760 btime 121940 for game ZtDrfFHQ
2024-01-30 17:16:40,004 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4
2024-01-30 17:16:40,004 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 95760 btime 121940 winc 2000 binc 2000
2024-01-30 17:16:44,824 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 163597 currmove h1g1 score cp 0
2024-01-30 17:16:44,824 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 293120 currmove h1g1 score cp 6
2024-01-30 17:16:44,824 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 2114375 currmove h1g1 score cp -18
2024-01-30 17:16:44,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 9414134 currmove e3g5 score cp -4
2024-01-30 17:16:44,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove e3g5
2024-01-30 17:16:44,825 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -0.04
2024-01-30 17:16:44,825 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:16:44,825 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 9.4M
2024-01-30 17:16:44,980 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/e3g5?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:16:44,981 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5', 'wtime': 92940, 'btime': 121940, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:16:46,619 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8', 'wtime': 92940, 'btime': 122320, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:16:46,620 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:16:46,620 __mp_main__ (lichess-bot.py:685) INFO move: 19
2024-01-30 17:16:46,620 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 92790 btime 122320 for game ZtDrfFHQ
2024-01-30 17:16:46,621 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8
2024-01-30 17:16:46,621 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 92790 btime 122320 winc 2000 binc 2000
2024-01-30 17:16:51,505 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 174805 currmove f2f4 score cp 138
2024-01-30 17:16:51,505 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 153488 currmove f2f4 score cp 115
2024-01-30 17:16:51,506 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 1923686 currmove d4c5 score cp -9
2024-01-30 17:16:51,506 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 9481258 currmove d1c1 score cp -31
2024-01-30 17:16:51,506 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d1c1
2024-01-30 17:16:51,506 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -0.31
2024-01-30 17:16:51,506 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:16:51,506 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 9.5M
2024-01-30 17:16:51,654 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d1c1?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:16:51,655 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1', 'wtime': 89880, 'btime': 122320, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:16:54,707 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6', 'wtime': 89880, 'btime': 121290, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:16:54,707 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:16:54,707 __mp_main__ (lichess-bot.py:685) INFO move: 20
2024-01-30 17:16:54,708 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 89730 btime 121290 for game ZtDrfFHQ
2024-01-30 17:16:54,708 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6
2024-01-30 17:16:54,708 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 89730 btime 121290 winc 2000 binc 2000
2024-01-30 17:16:59,551 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 60685 currmove h2h4 score cp -26
2024-01-30 17:16:59,551 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 206689 currmove h2h4 score cp -30
2024-01-30 17:16:59,552 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 716745 currmove h2h4 score cp -73
2024-01-30 17:16:59,552 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 1945104 currmove h2h4 score cp -116
2024-01-30 17:16:59,552 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove h2h4
2024-01-30 17:16:59,552 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.16
2024-01-30 17:16:59,552 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:16:59,552 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 1.9M
2024-01-30 17:16:59,701 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/h2h4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:16:59,702 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4', 'wtime': 86860, 'btime': 121290, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:02,452 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6', 'wtime': 86860, 'btime': 120540, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:02,453 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:02,453 __mp_main__ (lichess-bot.py:685) INFO move: 21
2024-01-30 17:17:02,453 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 86710 btime 120540 for game ZtDrfFHQ
2024-01-30 17:17:02,454 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6
2024-01-30 17:17:02,454 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 86710 btime 120540 winc 2000 binc 2000
2024-01-30 17:17:03,899 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 141544 currmove g5e3 score cp -73
2024-01-30 17:17:03,899 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 186704 currmove g5e3 score cp -116
2024-01-30 17:17:03,899 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 695873 currmove g5e3 score cp -130
2024-01-30 17:17:03,900 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 5348349 currmove g5e3 score cp -115
2024-01-30 17:17:03,900 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove g5e3
2024-01-30 17:17:03,900 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.15
2024-01-30 17:17:03,900 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:17:03,900 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 5.3M
2024-01-30 17:17:04,127 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/g5e3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:04,127 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3', 'wtime': 87260, 'btime': 120540, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:13,820 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8', 'wtime': 87260, 'btime': 112770, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:13,821 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:13,821 __mp_main__ (lichess-bot.py:685) INFO move: 22
2024-01-30 17:17:13,822 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 87110 btime 112770 for game ZtDrfFHQ
2024-01-30 17:17:13,822 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8
2024-01-30 17:17:13,822 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 87110 btime 112770 winc 2000 binc 2000
2024-01-30 17:17:14,404 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 154798 currmove c1a1 score cp -106
2024-01-30 17:17:14,404 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 99531 currmove c1a1 score cp -106
2024-01-30 17:17:14,404 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 561423 currmove c1a1 score cp -86
2024-01-30 17:17:14,404 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 1800896 currmove c1a1 score cp -127
2024-01-30 17:17:14,405 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove c1a1
2024-01-30 17:17:14,405 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.27
2024-01-30 17:17:14,405 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:17:14,405 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 1.8M
2024-01-30 17:17:14,406 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-30 17:17:15,065 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/c1a1?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:15,067 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1', 'wtime': 88010, 'btime': 112770, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:18,050 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4', 'wtime': 88010, 'btime': 111810, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:18,051 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:18,051 __mp_main__ (lichess-bot.py:685) INFO move: 23
2024-01-30 17:17:18,051 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 87860 btime 111810 for game ZtDrfFHQ
2024-01-30 17:17:18,051 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4
2024-01-30 17:17:18,051 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 87860 btime 111810 winc 2000 binc 2000
2024-01-30 17:17:23,153 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 43176 currmove d4d3 score cp -86
2024-01-30 17:17:23,154 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 73464 currmove d4d3 score cp -127
2024-01-30 17:17:23,154 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 537436 currmove d4d3 score cp -171
2024-01-30 17:17:23,154 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 1210721 currmove d4d3 score cp -171
2024-01-30 17:17:23,154 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 6323172 currmove d4d3 score cp -171
2024-01-30 17:17:23,154 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d4d3
2024-01-30 17:17:23,155 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.71
2024-01-30 17:17:23,155 engine_wrapper (engine_wrapper.py:283) INFO Depth: 7
2024-01-30 17:17:23,155 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 6.3M
2024-01-30 17:17:23,304 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d4d3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:23,307 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3', 'wtime': 84730, 'btime': 111810, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:26,242 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3', 'wtime': 84730, 'btime': 110870, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:26,243 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:26,243 __mp_main__ (lichess-bot.py:685) INFO move: 24
2024-01-30 17:17:26,244 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 84580 btime 110870 for game ZtDrfFHQ
2024-01-30 17:17:26,244 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3
2024-01-30 17:17:26,244 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 84580 btime 110870 winc 2000 binc 2000
2024-01-30 17:17:26,506 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 34985 currmove f2e3 score cp -171
2024-01-30 17:17:26,506 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 40090 currmove f2e3 score cp -171
2024-01-30 17:17:26,506 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 351012 currmove f2e3 score cp -171
2024-01-30 17:17:26,507 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 742887 currmove f2e3 score cp -174
2024-01-30 17:17:26,507 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove f2e3
2024-01-30 17:17:26,507 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.74
2024-01-30 17:17:26,507 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:17:26,507 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 742.9K
2024-01-30 17:17:26,752 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/f2e3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:26,753 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3', 'wtime': 86310, 'btime': 110870, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:28,288 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5', 'wtime': 86310, 'btime': 111270, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:28,289 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:28,290 __mp_main__ (lichess-bot.py:685) INFO move: 25
2024-01-30 17:17:28,290 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 86160 btime 111270 for game ZtDrfFHQ
2024-01-30 17:17:28,290 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5
2024-01-30 17:17:28,290 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 86160 btime 111270 winc 2000 binc 2000
2024-01-30 17:17:33,392 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 71700 currmove e1d2 score cp -171
2024-01-30 17:17:33,393 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 151572 currmove e1d2 score cp -171
2024-01-30 17:17:33,393 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 653337 currmove e1f2 score cp -197
2024-01-30 17:17:33,393 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 838040 currmove e1f2 score cp -213
2024-01-30 17:17:33,393 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove e1f2
2024-01-30 17:17:33,394 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -2.13
2024-01-30 17:17:33,394 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:17:33,394 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 838.0K
2024-01-30 17:17:33,612 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/e1f2?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:33,613 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2', 'wtime': 83020, 'btime': 111270, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:35,367 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5', 'wtime': 83020, 'btime': 111450, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:35,368 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:35,368 __mp_main__ (lichess-bot.py:685) INFO move: 26
2024-01-30 17:17:35,368 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 82870 btime 111450 for game ZtDrfFHQ
2024-01-30 17:17:35,369 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5
2024-01-30 17:17:35,369 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 82870 btime 111450 winc 2000 binc 2000
2024-01-30 17:17:40,353 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 113950 currmove f2f3 score cp -183
2024-01-30 17:17:40,354 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 220947 currmove f2f3 score cp -183
2024-01-30 17:17:40,354 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 1135991 currmove g2g3 score cp -168
2024-01-30 17:17:40,354 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 855689 currmove g2g3 score cp -187
2024-01-30 17:17:40,354 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 12197808 currmove g2g3 score cp -165
2024-01-30 17:17:40,354 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove g2g3
2024-01-30 17:17:40,355 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.65
2024-01-30 17:17:40,355 engine_wrapper (engine_wrapper.py:283) INFO Depth: 7
2024-01-30 17:17:40,355 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 12.2M
2024-01-30 17:17:40,574 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/g2g3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:40,575 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3', 'wtime': 79880, 'btime': 111450, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:44,977 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4', 'wtime': 79880, 'btime': 109070, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:44,978 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:44,978 __mp_main__ (lichess-bot.py:685) INFO move: 27
2024-01-30 17:17:44,978 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 79730 btime 109070 for game ZtDrfFHQ
2024-01-30 17:17:44,978 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4
2024-01-30 17:17:44,979 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 79730 btime 109070 winc 2000 binc 2000
2024-01-30 17:17:49,847 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 155156 currmove f1h3 score cp -40
2024-01-30 17:17:49,847 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 208894 currmove f1h3 score cp -58
2024-01-30 17:17:49,847 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 834420 currmove f1h3 score cp -142
2024-01-30 17:17:49,847 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 2510320 currmove f1h3 score cp -142
2024-01-30 17:17:49,847 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 13014638 currmove f1h3 score cp -131
2024-01-30 17:17:49,848 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove f1h3
2024-01-30 17:17:49,848 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.31
2024-01-30 17:17:49,848 engine_wrapper (engine_wrapper.py:283) INFO Depth: 7
2024-01-30 17:17:49,848 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 13.0M
2024-01-30 17:17:50,096 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/f1h3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:50,096 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3', 'wtime': 76760, 'btime': 109070, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:52,760 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8', 'wtime': 76760, 'btime': 108350, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:17:52,761 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:17:52,761 __mp_main__ (lichess-bot.py:685) INFO move: 28
2024-01-30 17:17:52,761 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 76610 btime 108350 for game ZtDrfFHQ
2024-01-30 17:17:52,762 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8
2024-01-30 17:17:52,762 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 76610 btime 108350 winc 2000 binc 2000
2024-01-30 17:17:57,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 218466 currmove h1d1 score cp -147
2024-01-30 17:17:57,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 313919 currmove h1d1 score cp -147
2024-01-30 17:17:57,444 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 2596166 currmove h1b1 score cp -130
2024-01-30 17:17:57,445 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 3227252 currmove h1d1 score cp -140
2024-01-30 17:17:57,445 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove h1d1
2024-01-30 17:17:57,445 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.4
2024-01-30 17:17:57,445 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:17:57,445 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 3.2M
2024-01-30 17:17:57,676 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/h1d1?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:17:57,676 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1', 'wtime': 73880, 'btime': 108350, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:00,334 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3', 'wtime': 73880, 'btime': 107710, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:00,336 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:00,336 __mp_main__ (lichess-bot.py:685) INFO move: 29
2024-01-30 17:18:00,336 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 73730 btime 107710 for game ZtDrfFHQ
2024-01-30 17:18:00,337 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3
2024-01-30 17:18:00,337 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 73730 btime 107710 winc 2000 binc 2000
2024-01-30 17:18:00,499 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 4799 currmove a1a3 score cp -122
2024-01-30 17:18:00,499 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 29509 currmove a1a3 score cp -140
2024-01-30 17:18:00,499 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 179348 currmove a1a3 score cp -126
2024-01-30 17:18:00,499 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 559086 currmove a1a3 score cp -142
2024-01-30 17:18:00,500 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove a1a3
2024-01-30 17:18:00,500 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.42
2024-01-30 17:18:00,500 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:00,500 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 559.1K
2024-01-30 17:18:00,744 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/a1a3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:00,745 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3', 'wtime': 75470, 'btime': 107710, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:01,153 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3', 'wtime': 75470, 'btime': 109230, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:01,154 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:01,154 __mp_main__ (lichess-bot.py:685) INFO move: 30
2024-01-30 17:18:01,154 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 75320 btime 109230 for game ZtDrfFHQ
2024-01-30 17:18:01,154 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3
2024-01-30 17:18:01,155 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 75320 btime 109230 winc 2000 binc 2000
2024-01-30 17:18:05,705 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 67192 currmove h3g2 score cp -126
2024-01-30 17:18:05,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 139655 currmove d3d4 score cp -142
2024-01-30 17:18:05,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 953418 currmove h3g2 score cp -131
2024-01-30 17:18:05,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 2951028 currmove d3d4 score cp -127
2024-01-30 17:18:05,706 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d3d4
2024-01-30 17:18:05,706 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.27
2024-01-30 17:18:05,707 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:05,707 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 3.0M
2024-01-30 17:18:05,863 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d3d4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:05,864 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4', 'wtime': 72740, 'btime': 109230, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:07,678 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5', 'wtime': 72740, 'btime': 109410, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:07,679 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:07,680 __mp_main__ (lichess-bot.py:685) INFO move: 31
2024-01-30 17:18:07,680 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 72590 btime 109410 for game ZtDrfFHQ
2024-01-30 17:18:07,680 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5
2024-01-30 17:18:07,681 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 72590 btime 109410 winc 2000 binc 2000
2024-01-30 17:18:09,742 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 28855 currmove d4e5 score cp -100
2024-01-30 17:18:09,743 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 73625 currmove d4e5 score cp -114
2024-01-30 17:18:09,743 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 200070 currmove d4e5 score cp -107
2024-01-30 17:18:09,743 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 782177 currmove d4e5 score cp -107
2024-01-30 17:18:09,743 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 1306879 currmove d4e5 score cp -105
2024-01-30 17:18:09,743 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 6144800 currmove d4e5 score cp -28
2024-01-30 17:18:09,743 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d4e5
2024-01-30 17:18:09,744 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -0.28
2024-01-30 17:18:09,744 engine_wrapper (engine_wrapper.py:283) INFO Depth: 8
2024-01-30 17:18:09,744 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 6.1M
2024-01-30 17:18:09,958 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d4e5?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:09,959 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5', 'wtime': 72520, 'btime': 109410, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:10,396 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7', 'wtime': 72520, 'btime': 110910, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:10,397 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:10,397 __mp_main__ (lichess-bot.py:685) INFO move: 32
2024-01-30 17:18:10,397 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 72370 btime 110910 for game ZtDrfFHQ
2024-01-30 17:18:10,398 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7
2024-01-30 17:18:10,398 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 72370 btime 110910 winc 2000 binc 2000
2024-01-30 17:18:14,693 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 128545 currmove h3f5 score cp -107
2024-01-30 17:18:14,694 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 182479 currmove h3f5 score cp -107
2024-01-30 17:18:14,694 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 285766 currmove h3f5 score cp -105
2024-01-30 17:18:14,694 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 2695071 currmove d1d3 score cp -28
2024-01-30 17:18:14,694 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d1d3
2024-01-30 17:18:14,694 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -0.28
2024-01-30 17:18:14,695 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:14,695 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 2.7M
2024-01-30 17:18:14,875 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d1d3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:14,876 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3', 'wtime': 70070, 'btime': 110910, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:15,692 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8', 'wtime': 70070, 'btime': 112150, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:15,694 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:15,694 __mp_main__ (lichess-bot.py:685) INFO move: 33
2024-01-30 17:18:15,694 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 69920 btime 112150 for game ZtDrfFHQ
2024-01-30 17:18:15,694 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8
2024-01-30 17:18:15,694 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 69920 btime 112150 winc 2000 binc 2000
2024-01-30 17:18:19,808 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 34559 currmove e5f5 score cp -30
2024-01-30 17:18:19,808 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 256796 currmove e5d6 score cp 20
2024-01-30 17:18:19,808 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 995858 currmove e5d6 score cp -121
2024-01-30 17:18:19,808 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 632996 currmove e5d6 score cp -121
2024-01-30 17:18:19,808 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 2848735 currmove e5d6 score cp -131
2024-01-30 17:18:19,808 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 9412856 currmove e5d6 score cp -131
2024-01-30 17:18:19,809 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove e5d6
2024-01-30 17:18:19,809 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.31
2024-01-30 17:18:19,809 engine_wrapper (engine_wrapper.py:283) INFO Depth: 8
2024-01-30 17:18:19,809 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 9.4M
2024-01-30 17:18:19,993 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/e5d6?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:19,993 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6', 'wtime': 67720, 'btime': 112150, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:20,459 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5', 'wtime': 67720, 'btime': 113660, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:20,460 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:20,461 __mp_main__ (lichess-bot.py:685) INFO move: 34
2024-01-30 17:18:20,461 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 67570 btime 113660 for game ZtDrfFHQ
2024-01-30 17:18:20,461 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5
2024-01-30 17:18:20,461 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 67570 btime 113660 winc 2000 binc 2000
2024-01-30 17:18:21,116 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 133216 currmove h3f5 score cp -121
2024-01-30 17:18:21,116 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 219420 currmove h3f5 score cp -121
2024-01-30 17:18:21,117 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 613209 currmove h3f5 score cp -135
2024-01-30 17:18:21,117 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 1520499 currmove h3f5 score cp -142
2024-01-30 17:18:21,117 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove h3f5
2024-01-30 17:18:21,118 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.42
2024-01-30 17:18:21,118 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:21,118 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 1.5M
2024-01-30 17:18:21,323 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/h3f5?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:21,324 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5', 'wtime': 68900, 'btime': 113660, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:21,733 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4', 'wtime': 68900, 'btime': 115240, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:21,734 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:21,735 __mp_main__ (lichess-bot.py:685) INFO move: 35
2024-01-30 17:18:21,735 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 68750 btime 115240 for game ZtDrfFHQ
2024-01-30 17:18:21,735 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4
2024-01-30 17:18:21,735 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 68750 btime 115240 winc 2000 binc 2000
2024-01-30 17:18:25,635 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 69008 currmove d3d1 score cp -135
2024-01-30 17:18:25,635 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 31756 currmove d3d1 score cp -142
2024-01-30 17:18:25,636 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 412592 currmove d3d1 score cp -131
2024-01-30 17:18:25,636 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 903153 currmove d3d4 score cp -161
2024-01-30 17:18:25,636 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 5079266 currmove d3d4 score cp -157
2024-01-30 17:18:25,636 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 5746235 currmove d3d4 score cp -161
2024-01-30 17:18:25,636 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d3d4
2024-01-30 17:18:25,636 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.61
2024-01-30 17:18:25,637 engine_wrapper (engine_wrapper.py:283) INFO Depth: 8
2024-01-30 17:18:25,637 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 5.7M
2024-01-30 17:18:25,830 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d3d4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:25,830 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4', 'wtime': 66800, 'btime': 115240, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:26,345 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3', 'wtime': 66800, 'btime': 116680, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:26,347 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:26,347 __mp_main__ (lichess-bot.py:685) INFO move: 36
2024-01-30 17:18:26,347 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 66650 btime 116680 for game ZtDrfFHQ
2024-01-30 17:18:26,347 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3
2024-01-30 17:18:26,347 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 66650 btime 116680 winc 2000 binc 2000
2024-01-30 17:18:26,663 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 70192 currmove d6f4 score cp -138
2024-01-30 17:18:26,663 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 118935 currmove d6f4 score cp -161
2024-01-30 17:18:26,664 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 278085 currmove d6f4 score cp -157
2024-01-30 17:18:26,664 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 850786 currmove d6f4 score cp -161
2024-01-30 17:18:26,664 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d6f4
2024-01-30 17:18:26,664 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.61
2024-01-30 17:18:26,664 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:26,664 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 850.8K
2024-01-30 17:18:26,860 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d6f4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:26,861 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4', 'wtime': 68330, 'btime': 116680, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:28,286 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5', 'wtime': 68330, 'btime': 117290, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:28,287 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:28,287 __mp_main__ (lichess-bot.py:685) INFO move: 37
2024-01-30 17:18:28,287 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 68180 btime 117290 for game ZtDrfFHQ
2024-01-30 17:18:28,288 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5
2024-01-30 17:18:28,288 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 68180 btime 117290 winc 2000 binc 2000
2024-01-30 17:18:31,946 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 94219 currmove d4d1 score cp -134
2024-01-30 17:18:31,946 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 298887 currmove f4g5 score cp -142
2024-01-30 17:18:31,946 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 1052790 currmove f5d7 score cp -132
2024-01-30 17:18:31,946 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 2341239 currmove f4g5 score cp -153
2024-01-30 17:18:31,947 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 3002947 currmove f4g5 score cp -149
2024-01-30 17:18:31,947 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove f4g5
2024-01-30 17:18:31,947 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.49
2024-01-30 17:18:31,947 engine_wrapper (engine_wrapper.py:283) INFO Depth: 7
2024-01-30 17:18:31,947 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 3.0M
2024-01-30 17:18:32,178 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/f4g5?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:32,178 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5', 'wtime': 66430, 'btime': 117290, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:32,791 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2', 'wtime': 66430, 'btime': 118600, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:32,792 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:32,793 __mp_main__ (lichess-bot.py:685) INFO move: 38
2024-01-30 17:18:32,793 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 66280 btime 118600 for game ZtDrfFHQ
2024-01-30 17:18:32,793 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2
2024-01-30 17:18:32,793 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 66280 btime 118600 winc 2000 binc 2000
2024-01-30 17:18:36,225 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 76031 currmove g3g4 score cp -142
2024-01-30 17:18:36,226 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 228021 currmove f5g6 score cp -144
2024-01-30 17:18:36,226 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 746972 currmove g3g4 score cp -149
2024-01-30 17:18:36,226 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 4556996 currmove f5g6 score cp -164
2024-01-30 17:18:36,226 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove f5g6
2024-01-30 17:18:36,226 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.64
2024-01-30 17:18:36,227 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:36,227 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 4.6M
2024-01-30 17:18:36,373 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/f5g6?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:36,376 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6', 'wtime': 64840, 'btime': 118600, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:36,682 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6', 'wtime': 64840, 'btime': 120380, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:36,684 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:36,684 __mp_main__ (lichess-bot.py:685) INFO move: 39
2024-01-30 17:18:36,684 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 64690 btime 120380 for game ZtDrfFHQ
2024-01-30 17:18:36,684 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6
2024-01-30 17:18:36,685 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 64690 btime 120380 winc 2000 binc 2000
2024-01-30 17:18:36,726 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 14064 currmove g5g6 score cp -156
2024-01-30 17:18:36,726 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 10098 currmove g5g6 score cp -164
2024-01-30 17:18:36,727 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 45136 currmove g5g6 score cp -159
2024-01-30 17:18:36,727 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 100843 currmove g5g6 score cp -156
2024-01-30 17:18:36,727 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove g5g6
2024-01-30 17:18:36,727 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.56
2024-01-30 17:18:36,727 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:36,727 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 100.8K
2024-01-30 17:18:36,887 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/g5g6?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:36,887 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6', 'wtime': 66560, 'btime': 120380, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:37,184 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8', 'wtime': 66560, 'btime': 122110, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:37,186 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:37,186 __mp_main__ (lichess-bot.py:685) INFO move: 40
2024-01-30 17:18:37,186 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 66410 btime 122110 for game ZtDrfFHQ
2024-01-30 17:18:37,187 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8
2024-01-30 17:18:37,187 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 66410 btime 122110 winc 2000 binc 2000
2024-01-30 17:18:37,206 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 1214 currmove d4f4 score cp -159
2024-01-30 17:18:37,207 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 7499 currmove d4f4 score cp -156
2024-01-30 17:18:37,207 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 11772 currmove d4f4 score cp -148
2024-01-30 17:18:37,207 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 33727 currmove d4f4 score cp -148
2024-01-30 17:18:37,207 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove d4f4
2024-01-30 17:18:37,207 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.48
2024-01-30 17:18:37,207 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:37,207 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 33.7K
2024-01-30 17:18:37,399 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/d4f4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:37,400 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4', 'wtime': 68340, 'btime': 122110, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:37,707 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5', 'wtime': 68340, 'btime': 123760, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:37,708 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:37,708 __mp_main__ (lichess-bot.py:685) INFO move: 41
2024-01-30 17:18:37,708 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 68190 btime 123760 for game ZtDrfFHQ
2024-01-30 17:18:37,709 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5
2024-01-30 17:18:37,709 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 68190 btime 123760 winc 2000 binc 2000
2024-01-30 17:18:40,860 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 3668 currmove f4f8 score cp -148
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 3865 currmove f4f8 score cp -148
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 32310 currmove g6g5 score cp -148
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 444146 currmove g6a6 score cp -163
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 382255 currmove g6a6 score cp -151
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 1444764 currmove g6a6 score cp -168
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 9 nodes 2232716 currmove g6a6 score cp -168
2024-01-30 17:18:40,861 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove g6a6
2024-01-30 17:18:40,862 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.68
2024-01-30 17:18:40,862 engine_wrapper (engine_wrapper.py:283) INFO Depth: 9
2024-01-30 17:18:40,862 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 2.2M
2024-01-30 17:18:41,010 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/g6a6?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:41,013 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6', 'wtime': 67030, 'btime': 123760, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:41,495 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7', 'wtime': 67030, 'btime': 125390, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:41,497 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:41,497 __mp_main__ (lichess-bot.py:685) INFO move: 42
2024-01-30 17:18:41,497 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 66880 btime 125390 for game ZtDrfFHQ
2024-01-30 17:18:41,498 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7
2024-01-30 17:18:41,498 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 66880 btime 125390 winc 2000 binc 2000
2024-01-30 17:18:44,462 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 5257 currmove a6g6 score cp -143
2024-01-30 17:18:44,462 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 15550 currmove a6a5 score cp -162
2024-01-30 17:18:44,462 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 37562 currmove f4f8 score cp -140
2024-01-30 17:18:44,463 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 208642 currmove a6c6 score cp -136
2024-01-30 17:18:44,463 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 229528 currmove a6c6 score cp -122
2024-01-30 17:18:44,463 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 668149 currmove a6c6 score cp -125
2024-01-30 17:18:44,463 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 9 nodes 3016383 currmove a6c6 score cp -119
2024-01-30 17:18:44,463 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove a6c6
2024-01-30 17:18:44,463 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.19
2024-01-30 17:18:44,463 engine_wrapper (engine_wrapper.py:283) INFO Depth: 9
2024-01-30 17:18:44,463 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 3.0M
2024-01-30 17:18:44,610 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/a6c6?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:44,612 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6', 'wtime': 65800, 'btime': 125390, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:44,981 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8', 'wtime': 65800, 'btime': 127120, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:44,983 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:44,983 __mp_main__ (lichess-bot.py:685) INFO move: 43
2024-01-30 17:18:44,983 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 65650 btime 127120 for game ZtDrfFHQ
2024-01-30 17:18:44,983 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8
2024-01-30 17:18:44,983 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 65650 btime 127120 winc 2000 binc 2000
2024-01-30 17:18:47,800 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 9094 currmove c6e6 score cp -94
2024-01-30 17:18:47,800 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 5096 currmove c6e6 score cp -95
2024-01-30 17:18:47,801 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 22156 currmove c6e6 score cp -98
2024-01-30 17:18:47,801 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 52575 currmove c6e6 score cp -112
2024-01-30 17:18:47,801 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 240934 currmove c6e6 score cp -111
2024-01-30 17:18:47,801 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 1084524 currmove c6e6 score cp -116
2024-01-30 17:18:47,801 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 9 nodes 2021756 currmove c6e6 score cp -105
2024-01-30 17:18:47,801 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove c6e6
2024-01-30 17:18:47,802 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.05
2024-01-30 17:18:47,802 engine_wrapper (engine_wrapper.py:283) INFO Depth: 9
2024-01-30 17:18:47,802 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 2.0M
2024-01-30 17:18:48,052 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/c6e6?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:48,052 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6', 'wtime': 64720, 'btime': 127120, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:48,162 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7', 'wtime': 64720, 'btime': 128910, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:48,163 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:48,164 __mp_main__ (lichess-bot.py:685) INFO move: 44
2024-01-30 17:18:48,164 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 64570 btime 128910 for game ZtDrfFHQ
2024-01-30 17:18:48,164 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7
2024-01-30 17:18:48,164 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 64570 btime 128910 winc 2000 binc 2000
2024-01-30 17:18:50,821 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 3194 currmove f4f8 score cp -98
2024-01-30 17:18:50,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 14846 currmove e6e5 score cp -112
2024-01-30 17:18:50,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 24972 currmove e6e5 score cp -111
2024-01-30 17:18:50,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 294650 currmove f2f3 score cp -116
2024-01-30 17:18:50,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 231905 currmove f2f3 score cp -105
2024-01-30 17:18:50,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 1152469 currmove f2f3 score cp -110
2024-01-30 17:18:50,822 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove f2f3
2024-01-30 17:18:50,823 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: -1.1
2024-01-30 17:18:50,823 engine_wrapper (engine_wrapper.py:283) INFO Depth: 8
2024-01-30 17:18:50,823 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 1.2M
2024-01-30 17:18:50,971 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/f2f3?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:50,974 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3', 'wtime': 63900, 'btime': 128910, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:51,323 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8', 'wtime': 63900, 'btime': 130640, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:51,324 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:51,324 __mp_main__ (lichess-bot.py:685) INFO move: 45
2024-01-30 17:18:51,325 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 63750 btime 130640 for game ZtDrfFHQ
2024-01-30 17:18:51,325 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8
2024-01-30 17:18:51,325 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 63750 btime 130640 winc 2000 binc 2000
2024-01-30 17:18:51,380 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 13979 currmove e6f7 score cp -11
2024-01-30 17:18:51,380 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 29005 currmove e6f7 score cp -47
2024-01-30 17:18:51,380 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 53323 currmove e6f7 score cp 4
2024-01-30 17:18:51,380 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 133291 currmove e6f7 score cp 19
2024-01-30 17:18:51,380 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove e6f7
2024-01-30 17:18:51,381 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: 0.19
2024-01-30 17:18:51,381 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:51,381 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 133.3K
2024-01-30 17:18:51,564 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/e6f7?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:51,565 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7', 'wtime': 65610, 'btime': 130640, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:51,798 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5', 'wtime': 65610, 'btime': 132370, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:51,799 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:51,799 __mp_main__ (lichess-bot.py:685) INFO move: 46
2024-01-30 17:18:51,800 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 65460 btime 132370 for game ZtDrfFHQ
2024-01-30 17:18:51,800 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5
2024-01-30 17:18:51,800 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 65460 btime 132370 winc 2000 binc 2000
2024-01-30 17:18:51,952 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 21963 currmove f7a7 score cp 4
2024-01-30 17:18:51,952 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 44578 currmove f7a7 score cp 19
2024-01-30 17:18:51,952 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 123588 currmove f7a7 score cp 25
2024-01-30 17:18:51,952 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 392182 currmove f7a7 score cp 12
2024-01-30 17:18:51,953 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove f7a7
2024-01-30 17:18:51,953 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: 0.12
2024-01-30 17:18:51,953 engine_wrapper (engine_wrapper.py:283) INFO Depth: 6
2024-01-30 17:18:51,953 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 392.2K
2024-01-30 17:18:52,245 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/f7a7?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:52,245 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7', 'wtime': 67270, 'btime': 132370, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:52,551 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8', 'wtime': 67270, 'btime': 134000, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:52,553 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:52,553 __mp_main__ (lichess-bot.py:685) INFO move: 47
2024-01-30 17:18:52,553 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 67120 btime 134000 for game ZtDrfFHQ
2024-01-30 17:18:52,554 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8
2024-01-30 17:18:52,554 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 67120 btime 134000 winc 2000 binc 2000
2024-01-30 17:18:55,019 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 3 nodes 23818 currmove a7c5 score cp 25
2024-01-30 17:18:55,019 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 4 nodes 48173 currmove a7c5 score cp 18
2024-01-30 17:18:55,020 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 5 nodes 102103 currmove a7c5 score cp 19
2024-01-30 17:18:55,020 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 6 nodes 676693 currmove a7d4 score cp 12
2024-01-30 17:18:55,020 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 7 nodes 410297 currmove a7d4 score cp 5
2024-01-30 17:18:55,020 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> info depth 8 nodes 1595884 currmove a7d4 score cp 3
2024-01-30 17:18:55,020 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=141378)>: >> bestmove a7d4
2024-01-30 17:18:55,020 engine_wrapper (engine_wrapper.py:283) INFO Evaluation: 0.03
2024-01-30 17:18:55,021 engine_wrapper (engine_wrapper.py:283) INFO Depth: 8
2024-01-30 17:18:55,021 engine_wrapper (engine_wrapper.py:283) INFO Nodes: 1.6M
2024-01-30 17:18:55,167 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "POST /api/bot/game/ZtDrfFHQ/move/a7d4?offeringDraw=false HTTP/1.1" 200 11
2024-01-30 17:18:55,170 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4', 'wtime': 66610, 'btime': 134000, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:55,522 __mp_main__ (lichess-bot.py:693) DEBUG Game state: {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 66610, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}
2024-01-30 17:18:55,523 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:55,523 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:18:55,524 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 66460 btime 135710 for game ZtDrfFHQ
2024-01-30 17:18:55,524 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:18:55,524 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=141378)>: << go wtime 66460 btime 135710 winc 2000 binc 2000
2024-01-30 17:18:56,885 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=141378)>: Process exited
2024-01-30 17:18:56,886 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=141378)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:18:56,887 backoff (_common.py:105) INFO Backing off play_game(...) for 0.7s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:18:56,887 lichess (lichess.py:62) DEBUG Backing off 0.7 seconds after 1 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:18:56,888 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:18:57,776 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:18:57,778 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 64330, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:18:57,778 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:18:57,778 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:18:57,779 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:18:57,779 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=142457)>: Connection made
2024-01-30 17:18:57,779 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142457)>: << uci
2024-01-30 17:18:57,781 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142457)>: >> id name Potato Chess
2024-01-30 17:18:57,781 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142457)>: >> id author Jayden Li
2024-01-30 17:18:57,781 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142457)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:18:57,781 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142457)>: >> uciok
2024-01-30 17:18:57,781 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142457)>: << setoption name Move Overhead value 190
2024-01-30 17:18:57,782 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=142457
2024-01-30 17:18:57,782 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:18:57,783 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:18:57,783 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:18:57,783 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 64180 btime 135710 for game ZtDrfFHQ
2024-01-30 17:18:57,783 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142457)>: << ucinewgame
2024-01-30 17:18:57,783 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142457)>: << isready
2024-01-30 17:18:57,783 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142457)>: >> readyok
2024-01-30 17:18:57,783 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142457)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:18:57,784 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142457)>: << go wtime 64180 btime 135710 winc 2000 binc 2000
2024-01-30 17:18:59,051 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=142457)>: Process exited
2024-01-30 17:18:59,051 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=142457)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:18:59,052 backoff (_common.py:105) INFO Backing off play_game(...) for 1.8s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:18:59,052 lichess (lichess.py:62) DEBUG Backing off 1.8 seconds after 2 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:18:59,053 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:19:00,891 urllib3.connectionpool (connectionpool.py:1048) DEBUG Starting new HTTPS connection (3): lichess.org:443
2024-01-30 17:19:01,543 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:19:01,545 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 60520, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:19:01,545 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:19:01,546 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:19:01,546 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:19:01,547 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=142510)>: Connection made
2024-01-30 17:19:01,547 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142510)>: << uci
2024-01-30 17:19:01,549 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142510)>: >> id name Potato Chess
2024-01-30 17:19:01,549 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142510)>: >> id author Jayden Li
2024-01-30 17:19:01,549 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142510)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:19:01,549 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142510)>: >> uciok
2024-01-30 17:19:01,551 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142510)>: << setoption name Move Overhead value 190
2024-01-30 17:19:01,551 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=142510
2024-01-30 17:19:01,552 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:19:01,553 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:19:01,553 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:19:01,553 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 60370 btime 135710 for game ZtDrfFHQ
2024-01-30 17:19:01,553 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142510)>: << ucinewgame
2024-01-30 17:19:01,553 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142510)>: << isready
2024-01-30 17:19:01,553 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142510)>: >> readyok
2024-01-30 17:19:01,554 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142510)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:19:01,554 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142510)>: << go wtime 60370 btime 135710 winc 2000 binc 2000
2024-01-30 17:19:02,762 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=142510)>: Process exited
2024-01-30 17:19:02,762 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=142510)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:19:02,763 backoff (_common.py:105) INFO Backing off play_game(...) for 0.9s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:19:02,763 lichess (lichess.py:62) DEBUG Backing off 0.9 seconds after 3 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:19:02,764 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:19:03,654 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-30 17:19:04,258 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:19:04,262 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 57810, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:19:04,262 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:19:04,263 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:19:04,263 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:19:04,264 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=142530)>: Connection made
2024-01-30 17:19:04,264 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142530)>: << uci
2024-01-30 17:19:04,266 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142530)>: >> id name Potato Chess
2024-01-30 17:19:04,266 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142530)>: >> id author Jayden Li
2024-01-30 17:19:04,266 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142530)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:19:04,266 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142530)>: >> uciok
2024-01-30 17:19:04,266 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142530)>: << setoption name Move Overhead value 190
2024-01-30 17:19:04,267 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=142530
2024-01-30 17:19:04,267 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:19:04,269 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:19:04,269 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:19:04,269 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 57660 btime 135710 for game ZtDrfFHQ
2024-01-30 17:19:04,269 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142530)>: << ucinewgame
2024-01-30 17:19:04,269 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142530)>: << isready
2024-01-30 17:19:04,269 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142530)>: >> readyok
2024-01-30 17:19:04,269 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142530)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:19:04,270 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142530)>: << go wtime 57660 btime 135710 winc 2000 binc 2000
2024-01-30 17:19:05,482 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=142530)>: Process exited
2024-01-30 17:19:05,482 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=142530)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:19:05,483 backoff (_common.py:105) INFO Backing off play_game(...) for 0.5s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:19:05,483 lichess (lichess.py:62) DEBUG Backing off 0.5 seconds after 4 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:19:05,484 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:19:06,001 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-30 17:19:06,582 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:19:06,585 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 55480, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:19:06,585 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:19:06,587 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:19:06,587 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:19:06,587 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=142583)>: Connection made
2024-01-30 17:19:06,588 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142583)>: << uci
2024-01-30 17:19:06,589 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142583)>: >> id name Potato Chess
2024-01-30 17:19:06,589 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142583)>: >> id author Jayden Li
2024-01-30 17:19:06,589 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142583)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:19:06,590 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142583)>: >> uciok
2024-01-30 17:19:06,590 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142583)>: << setoption name Move Overhead value 190
2024-01-30 17:19:06,590 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=142583
2024-01-30 17:19:06,591 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:19:06,592 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:19:06,592 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:19:06,592 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 55330 btime 135710 for game ZtDrfFHQ
2024-01-30 17:19:06,593 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142583)>: << ucinewgame
2024-01-30 17:19:06,593 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142583)>: << isready
2024-01-30 17:19:06,593 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142583)>: >> readyok
2024-01-30 17:19:06,593 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142583)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:19:06,593 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142583)>: << go wtime 55330 btime 135710 winc 2000 binc 2000
2024-01-30 17:19:07,771 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=142583)>: Process exited
2024-01-30 17:19:07,771 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=142583)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:19:07,772 backoff (_common.py:105) INFO Backing off play_game(...) for 14.1s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:19:07,772 lichess (lichess.py:62) DEBUG Backing off 14.1 seconds after 5 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:19:07,773 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:19:21,908 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-30 17:19:22,541 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:19:22,543 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 39520, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:19:22,544 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:19:22,544 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:19:22,544 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:19:22,545 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=142636)>: Connection made
2024-01-30 17:19:22,545 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142636)>: << uci
2024-01-30 17:19:22,546 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142636)>: >> id name Potato Chess
2024-01-30 17:19:22,546 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142636)>: >> id author Jayden Li
2024-01-30 17:19:22,547 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142636)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:19:22,547 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142636)>: >> uciok
2024-01-30 17:19:22,547 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142636)>: << setoption name Move Overhead value 190
2024-01-30 17:19:22,547 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=142636
2024-01-30 17:19:22,547 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:19:22,548 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:19:22,548 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:19:22,548 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 39370 btime 135710 for game ZtDrfFHQ
2024-01-30 17:19:22,549 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142636)>: << ucinewgame
2024-01-30 17:19:22,549 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142636)>: << isready
2024-01-30 17:19:22,550 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142636)>: >> readyok
2024-01-30 17:19:22,550 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142636)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:19:22,550 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142636)>: << go wtime 39370 btime 135710 winc 2000 binc 2000
2024-01-30 17:19:23,742 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=142636)>: Process exited
2024-01-30 17:19:23,742 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=142636)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:19:23,743 backoff (_common.py:105) INFO Backing off play_game(...) for 14.4s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:19:23,744 lichess (lichess.py:62) DEBUG Backing off 14.4 seconds after 6 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:19:23,744 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:19:38,191 urllib3.connectionpool (connectionpool.py:292) DEBUG Resetting dropped connection: lichess.org
2024-01-30 17:19:38,819 urllib3.connectionpool (connectionpool.py:546) DEBUG https://lichess.org:443 "GET /api/bot/game/stream/ZtDrfFHQ HTTP/1.1" 200 None
2024-01-30 17:19:38,821 __mp_main__ (lichess-bot.py:571) DEBUG Initial state: {'id': 'ZtDrfFHQ', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': True, 'createdAt': 1706652910911, 'white': {'id': 'potato-chess-bot', 'name': 'potato-chess-bot', 'title': 'BOT', 'rating': 1860}, 'black': {'id': 'flounderbot', 'name': 'FlounderBot', 'title': 'BOT', 'rating': 1777}, 'initialFen': 'startpos', 'clock': {'initial': 120000, 'increment': 2000}, 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': 'd2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6', 'wtime': 23240, 'btime': 135710, 'winc': 2000, 'binc': 2000, 'status': 'started'}}
2024-01-30 17:19:38,821 engine_wrapper (engine_wrapper.py:64) DEBUG Starting engine: ['/home/jayden/Desktop/Programs/potato-chess/potato-chess-uci/build/potato-chess-uci']
2024-01-30 17:19:38,821 asyncio (selector_events.py:54) DEBUG Using selector: EpollSelector
2024-01-30 17:19:38,822 chess.engine (engine.py:124) DEBUG Using PidfdChildWatcher
2024-01-30 17:19:38,822 chess.engine (engine.py:1040) DEBUG <UciProtocol (pid=142732)>: Connection made
2024-01-30 17:19:38,823 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142732)>: << uci
2024-01-30 17:19:38,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142732)>: >> id name Potato Chess
2024-01-30 17:19:38,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142732)>: >> id author Jayden Li
2024-01-30 17:19:38,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142732)>: >> option name Move Overhead type spin default 10 min 0 max 5000
2024-01-30 17:19:38,825 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142732)>: >> uciok
2024-01-30 17:19:38,826 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142732)>: << setoption name Move Overhead value 190
2024-01-30 17:19:38,826 __mp_main__ (lichess-bot.py:577) DEBUG The engine for game ZtDrfFHQ has pid=142732
2024-01-30 17:19:38,826 __mp_main__ (lichess-bot.py:580) INFO +++ https://lichess.org/ZtDrfFHQ/white Blitz vs BOT FlounderBot (1777) (ZtDrfFHQ)
2024-01-30 17:19:38,827 __mp_main__ (lichess-bot.py:684) INFO 
2024-01-30 17:19:38,827 __mp_main__ (lichess-bot.py:685) INFO move: 48
2024-01-30 17:19:38,827 engine_wrapper (engine_wrapper.py:612) INFO Searching for wtime 23090 btime 135710 for game ZtDrfFHQ
2024-01-30 17:19:38,828 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142732)>: << ucinewgame
2024-01-30 17:19:38,828 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142732)>: << isready
2024-01-30 17:19:38,828 chess.engine (engine.py:1088) DEBUG <UciProtocol (pid=142732)>: >> readyok
2024-01-30 17:19:38,828 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142732)>: << position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
2024-01-30 17:19:38,828 chess.engine (engine.py:1062) DEBUG <UciProtocol (pid=142732)>: << go wtime 23090 btime 135710 winc 2000 binc 2000
2024-01-30 17:19:40,059 chess.engine (engine.py:1059) DEBUG <UciProtocol (pid=142732)>: Process exited
2024-01-30 17:19:40,059 chess.engine (engine.py:1046) DEBUG <UciProtocol (pid=142732)>: Connection lost (exit code: -11, error: None)
2024-01-30 17:19:40,060 backoff (_common.py:105) INFO Backing off play_game(...) for 46.2s (chess.engine.EngineTerminatedError: engine event loop dead)
2024-01-30 17:19:40,060 lichess (lichess.py:62) DEBUG Backing off 46.2 seconds after 7 tries calling function <function play_game at 0x734522eb7d80> with args () and kwargs {'li': <lichess.Lichess object at 0x734522ece210>, 'control_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d24310>, 'user_profile': {'id': 'potato-chess-bot', 'username': 'potato-chess-bot', 'perfs': {'blitz': {'games': 450, 'rating': 1860, 'rd': 81, 'prog': -26}, 'bullet': {'games': 352, 'rating': 1952, 'rd': 81, 'prog': -20}, 'correspondence': {'games': 0, 'rating': 2000, 'rd': 500, 'prog': 0, 'prov': True}, 'classical': {'games': 4, 'rating': 1620, 'rd': 210, 'prog': 0, 'prov': True}, 'rapid': {'games': 56, 'rating': 1947, 'rd': 94, 'prog': -22}}, 'title': 'BOT', 'createdAt': 1690966236147, 'profile': {'location': 'New Jersey', 'firstName': 'Potato', 'flag': 'US', 'bio': 'My first attempt at chess programming. Uses multithreaded principal variation search, runs on a laptop with Intel i7-1165G7 (8) @ 4.700GHz. Accepts any time control, casual or rated.', 'lastName': 'Chess', 'links': 'https://github.com/glolichen/potato-chess-uci/'}, 'seenAt': 1706652464378, 'playTime': {'total': 357301, 'tv': 0}, 'url': 'https://lichess.org/@/potato-chess-bot', 'playing': 'https://lichess.org/ZtDrfFHQ/white', 'count': {'all': 1082, 'rated': 907, 'ai': 0, 'draw': 166, 'drawH': 166, 'loss': 433, 'lossH': 433, 'win': 483, 'winH': 483, 'bookmark': 0, 'playing': 1, 'import': 0, 'me': 0}, 'followable': True, 'following': False, 'blocking': False, 'followsYou': False}, 'config': <config.Configuration object at 0x734522d25d10>, 'challenge_queue': <ListProxy object, typeid 'list' at 0x734522d280d0>, 'correspondence_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d28290>, 'logging_queue': <AutoProxy[Queue] object, typeid 'Queue' at 0x734522d284d0>, 'game_id': 'ZtDrfFHQ'}
2024-01-30 17:19:40,060 lichess (lichess.py:64) DEBUG Exception: Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 67, in create_engine
    yield engine
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 620, in play_game
    engine.play_move(board,
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 157, in play_move
    best_move = self.search(board, time_limit, can_ponder, draw_offered, best_move)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 221, in search
    result = self.engine.play(board,
             ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2982, in play
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 456, in result
    return self.__get_result()
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/concurrent/futures/_base.py", line 401, in __get_result
    raise self._exception
  File "/usr/lib/python3.11/asyncio/tasks.py", line 452, in wait_for
    return await fut
           ^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1789, in play
    return await self.communicate(UciPlayCommand)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 1133, in communicate
    return await command.result
           ^^^^^^^^^^^^^^^^^^^^
chess.engine.EngineTerminatedError: engine process died unexpectedly (exit code: -11)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/backoff/_sync.py", line 105, in retry
    ret = target(*args, **kwargs)
          ^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/lichess-bot.py", line 575, in play_game
    with engine_wrapper.create_engine(config) as engine:
  File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 69, in create_engine
    engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/engine_wrapper.py", line 378, in ping
    self.engine.ping()
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2971, in ping
    with self._not_shut_down():
  File "/usr/lib/python3.11/contextlib.py", line 137, in __enter__
    return next(self.gen)
           ^^^^^^^^^^^^^^
  File "/home/jayden/Desktop/Programs/potato-chess/lichess-bot/venv/lib/python3.11/site-packages/chess/engine.py", line 2933, in _not_shut_down
    raise EngineTerminatedError("engine event loop dead")
chess.engine.EngineTerminatedError: engine event loop dead

2024-01-30 17:19:43,849 __main__ (lichess-bot.py:381) DEBUG Event: {'type': 'gameFinish', 'game': {'fullId': 'ZtDrfFHQWYaX', 'gameId': 'ZtDrfFHQ', 'fen': '8/6pk/4q3/3pr2p/2pQ1R1P/4PKP1/4P3/8 w - - 3 48', 'color': 'white', 'lastMove': 'e8e6', 'source': 'friend', 'status': {'id': 31, 'name': 'resign'}, 'variant': {'key': 'standard', 'name': 'Standard'}, 'speed': 'blitz', 'perf': 'blitz', 'rated': True, 'hasMoved': True, 'opponent': {'id': 'flounderbot', 'username': 'BOT FlounderBot', 'rating': 1777, 'ratingDiff': 7}, 'isMyTurn': False, 'secondsLeft': 18, 'winner': 'black', 'ratingDiff': -22, 'compat': {'bot': True, 'board': True}, 'id': 'ZtDrfFHQ'}}
2024-01-30 17:19:43,850 __main__ (lichess-bot.py:264) INFO +++ Process Freed. Count: 0. IDs: None
2024-01-30 17:19:50,233 __mp_main__ (lichess-bot.py:66) DEBUG Received SIGINT. Terminating client.
2024-01-30 17:19:50,233 __mp_main__ (lichess-bot.py:66) DEBUG Received SIGINT. Terminating client.

from lichess-bot.

MarkZH commented on June 2, 2024

From the logs you posted, it looks like something causes your engine to shut down in the last position:

8/6pk/4q3/3pr2p/2pQ1R1P/4PKP1/4P3/8 w - - 3 48

The first thing I'd like to try is to recreate the position on your machine using just your engine. Save the following lines to a python file uci.py.

import time

commands="""uci
setoption name Move Overhead value 190
ucinewgame
isready
position startpos moves d2d4 d7d5 c2c4 b8c6 g1f3 e7e6 c4d5 e6d5 c1e3 g8f6 b1c3 f8b4 a2a3 b4c3 b2c3 e8g8 d1d3 f6e4 f3e5 c6e5 d4e5 c8f5 d3b5 f8e8 b5b7 e8e5 a1d1 e4d6 b7a6 f5c8 a6d3 c8f5 d3d4 d6c4 e3g5 d8e8 d1c1 f5e6 h2h4 f7f6 g5e3 e8f8 c1a1 e5e4 d4d3 c4e3 f2e3 f8c5 e1f2 f6f5 g2g3 e4a4 f1h3 a8f8 h1d1 a4a3 a1a3 c5a3 d3d4 c7c5 d4e5 e6f7 d1d3 f8e8 e5d6 a3a5 h3f5 c5c4 d3d4 a5c3 d6f4 h7h5 f4g5 c3b2 f5g6 f7g6 g5g6 e8f8 d4f4 b2e5 g6a6 e5e7 a6c6 e7d8 c6e6 g8h7 f2f3 f8e8 e6f7 e8e5 f7a7 d8e8 a7d4 e8e6
go wtime 66460 btime 135710 winc 2000 binc 2000
quit""".splitlines()


for command in commands:
    if command == "quit":
        time.sleep(10)  # to give the engine time to think
    print(command, flush=True)

Then, run your engine like this:

python uci.py | potato-chess-uci

This will test whether the problem is in the engine or elsewhere.

from lichess-bot.

MarkZH commented on June 2, 2024

Also, you said that the problems started when you turned on pondering. Before UCI engines ponder, they add a pondermove to their bestmove response. Like this: bestmove e2e4 pondermove e7e5. The pondermove is a prediction of the opponent's likely move. This will cause the game to issue a go ponder command to start the engine pondering. If your engine is pondering without receiving a go ponder command, that may cause communication problems.

from lichess-bot.

MarkZH commented on June 2, 2024

Correction: the keyword for pondering is ponder, not pondermove. So, the example above should be bestmove e2e4 ponder e7e5.

from lichess-bot.

glolichen commented on June 2, 2024

Thanks. The program segfaults on the position you gave earlier.

from lichess-bot.

MarkZH commented on June 2, 2024

Good luck with debugging. Feel free to reopen if this issue comes up again.

from lichess-bot.

What is the meaning of chess.engine.EngineTerminatedError: engine event loop dead? about lichess-bot HOT 7 CLOSED

Comments (7)

Related Issues (20)

Recommend Projects

React

Vue.js

Typescript

TensorFlow

Django

Laravel

D3

Recommend Topics

javascript

web

server

Machine learning

Visualization

Game

Recommend Org

Facebook

Microsoft

Google

Alibaba

D3

Tencent