The only change was unchecking Thinking on opponent's time (Ponder) box in Cute Chess GUI 1.1.0.
My own situation is now clarified. However, would like to help in resolving issue.
1. Any idea on the cause should be capable of explaining why problem arises only with long time controls.
Have run thousands of 10s+1s gauntlets untroubled, with pondering enabled and using Cute Chess GUI.
Current matches have a time control of 40m + 12s / move. There have been 3 trial runs and, now, 3 matches. Trouble-free only when turned off pondering in last match.
2. It might not be a bug in lc0's coding. Perhaps it's just a need for GUI to allow for an eccentricity in the implementation of the UCI protocol.
3. Read lc0 ponder stop issue again. Decided to compare my log output from Stockfish with same-situation output from lc0. Didn't see that lc0 omitted any responses, although it is more verbose.
Code: Select all
Stockfish 11
------------
GO PONDER:
<< bestmove g8f6 ponder c1g5
>> position startpos moves e2e4 e7e6 d2d4 d7d5 b1c3 g8f6 c1g5
>> isready
<< readyok
>> go ponder wtime 2380958 btime 2187085 winc 12000 binc 12000
<< info depth 1 seldepth 1 multipv 1 score cp -13 nodes 205 nps 102500 tbhits 0 time 2 pv d5e4
STOP:
<< info depth 23 seldepth 39 multipv 1 score cp -20 nodes 4220857 […]
>> stop
<< info depth 41 seldepth 5 multipv 1 score cp 0 nodes 430693475 […]
<< bestmove f8e7 ponder e4e5
>> position startpos moves e2e4 e7e6 d2d4 d7d5 b1c3 g8f6 e4e5
>> isready
<< readyok
>> go wtime 2391685 btime 2187085 winc 12000 binc 12000
<< info depth 1 seldepth 1 multipv 1 score cp 0 nodes 221 […]
-------------------------------------------------------------
lc0
---
GO PONDER:
0209 16:05:05.147405 9732 c:\projects\lc0\src\chess\uciloop.cc:219] << bestmove b1c3 ponder g8f6
0209 16:05:05.147428 9732 c:\projects\lc0\src\mcts\search.cc:624] End a watchdog thread.
0209 16:05:05.147932 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> position startpos moves e2e4 […]
0209 16:05:05.148004 8580 c:\projects\lc0\src\mcts\search.cc:646] Aborting search, if it is still active.
0209 16:05:05.702115 8580 c:\projects\lc0\src\mcts\search.cc:660] Search destroyed.
0209 16:05:05.702195 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> isready
0209 16:05:05.702234 8580 c:\projects\lc0\src\chess\uciloop.cc:219] << readyok
0209 16:05:05.702429 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> go ponder wtime 2380958 btime […]
0209 16:05:05.702527 8580 c:\projects\lc0\src\engine.cc:259] Timer started at 0209 16:05:05.702232
0209 16:05:05.702744 6816 c:\projects\lc0\src\mcts\search.cc:593] Start a watchdog thread.
0209 16:05:05.702914 4604 c:\projects\lc0\src\mcts\search.h:195] Started search thread.
0209 16:05:05.703077 8580 c:\projects\lc0\src\mcts\search.cc:560] Search started. 0ms already passed.
0209 16:05:05.703066 8264 c:\projects\lc0\src\mcts\search.h:195] Started search thread.
0209 16:05:05.808868 6816 c:\projects\lc0\src\chess\uciloop.cc:219] << info depth 20 seldepth 55 […]
STOP:
0209 16:25:52.933474 8864 c:\projects\lc0\src\chess\uciloop.cc:219] << info depth 30 seldepth 60 […]
0209 16:25:57.802444 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> stop
0209 16:25:57.802510 8580 c:\projects\lc0\src\mcts\search.cc:636] Stopping search due to `stop` uci command.
0209 16:25:57.802888 8864 c:\projects\lc0\src\chess\uciloop.cc:219] << info depth 30 seldepth 60 […]
0209 16:25:57.803551 8864 c:\projects\lc0\src\chess\uciloop.cc:219] << info string e7h4 (327 ) N: 1370245 […]
0209 16:25:57.803640 8864 c:\projects\lc0\src\mcts\search.cc:296] --- Opponent moves after: e7h4
0209 16:25:57.803836 8864 c:\projects\lc0\src\mcts\search.cc:301] e1e2 (106 ) N: 6582 […]
[…]
0209 16:25:57.804165 8864 c:\projects\lc0\src\chess\uciloop.cc:219] << bestmove e7h4 ponder d2f2
0209 16:25:57.804207 8864 c:\projects\lc0\src\mcts\search.cc:624] End a watchdog thread.
0209 16:25:57.804295 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> position startpos moves e2e4 […]
0209 16:25:57.804390 8580 c:\projects\lc0\src\mcts\search.cc:646] Aborting search, if it is still active.
0209 16:25:58.319534 8580 c:\projects\lc0\src\mcts\search.cc:660] Search destroyed.
0209 16:25:58.319626 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> isready
0209 16:25:58.319668 8580 c:\projects\lc0\src\chess\uciloop.cc:219] << readyok
0209 16:25:58.319927 8580 c:\projects\lc0\src\chess\uciloop.cc:132] >> go wtime 2170501 btime […]
0209 […]320011 8580 […]\mcts\stoppers\factory.cc:281] Budgeted time for the move: 60233.6ms(+397649ms to squander). Remaining time 2170501ms(-200ms overhead)
0209 16:25:58.320051 8580 c:\projects\lc0\src\engine.cc:259] Timer started at 0209 16:25:58.319666
0209 16:25:58.320328 1716 c:\projects\lc0\src\mcts\search.cc:593] Start a watchdog thread.
0209 16:25:58.320477 784 c:\projects\lc0\src\mcts\search.h:195] Started search thread.
0209 16:25:58.320645 8580 c:\projects\lc0\src\mcts\search.cc:560] Search started. 0ms already passed.
0209 16:25:58.320635 3104 c:\projects\lc0\src\mcts\search.h:195] Started search thread.
0209 16:25:58.433411 1716 c:\projects\lc0\src\chess\uciloop.cc:219] << info depth 23 seldepth 27 […]