Time losses at CCC

[mstembera]

Describe the issue

In the current Leela vs SF 8:1 odds match SF lost several games on time.

Expected behavior

No losses on time.

Steps to reproduce

https://www.chess.com/computer-chess-championship#event=479&game=26
https://www.chess.com/computer-chess-championship#event=479&game=70
https://www.chess.com/computer-chess-championship#event=479&game=80

Anything else?

No response

Operating system

Linux

Stockfish version

dev-20260527-313ea4ab

[vondele]

Discussed a bit in discord:

AGE:

https://storage.googleapis.com/chess-1-prod-ccc/gamelogs/game-192609.log
In the final reporting before the timeloss, there is a 2s delay between depth 224 an depth 225, which is what eats up the time + the margin. But notably, SF reports the time <ms> without the 2s jump.
That makes me want to absolve SF, and then either blame Fastchess, or blame some external factor on the CCC box. There is no Network connection factor here like in the past.

[Engine] [13:30:38.327152] <     139680131787568> Stockfish ---> info depth 214 seldepth 6 multipv 1 score mate 3 nodes 1211631 nps 48465240 hashfull 2 tbhits 18 time 25 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327246] <     139680131787568> Stockfish ---> info depth 215 seldepth 6 multipv 1 score mate 3 nodes 1211651 nps 46601961 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327322] <     139680131787568> Stockfish ---> info depth 216 seldepth 6 multipv 1 score mate 3 nodes 1211671 nps 46602730 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327408] <     139680131787568> Stockfish ---> info depth 217 seldepth 6 multipv 1 score mate 3 nodes 1211691 nps 46603500 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327495] <     139680131787568> Stockfish ---> info depth 218 seldepth 6 multipv 1 score mate 3 nodes 1211711 nps 46604269 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327569] <     139680131787568> Stockfish ---> info depth 219 seldepth 6 multipv 1 score mate 3 nodes 1211731 nps 46605038 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327652] <     139680131787568> Stockfish ---> info depth 220 seldepth 6 multipv 1 score mate 3 nodes 1211751 nps 46605807 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327738] <     139680131787568> Stockfish ---> info depth 221 seldepth 6 multipv 1 score mate 3 nodes 1211771 nps 46606576 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327814] <     139680131787568> Stockfish ---> info depth 222 seldepth 6 multipv 1 score mate 3 nodes 1211791 nps 46607346 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327901] <     139680131787568> Stockfish ---> info depth 223 seldepth 6 multipv 1 score mate 3 nodes 1211811 nps 46608115 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:38.327977] <     139680131787568> Stockfish ---> info depth 224 seldepth 6 multipv 1 score mate 3 nodes 1211831 nps 46608884 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552531] <     139680131787568> Stockfish ---> info depth 225 seldepth 6 multipv 1 score mate 3 nodes 1211851 nps 46609653 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552618] <     139680131787568> Stockfish ---> info depth 226 seldepth 6 multipv 1 score mate 3 nodes 1211871 nps 46610423 hashfull 2 tbhits 18 time 26 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552631] <     139680131787568> Stockfish ---> info depth 227 seldepth 6 multipv 1 score mate 3 nodes 1211891 nps 44884851 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552642] <     139680131787568> Stockfish ---> info depth 228 seldepth 6 multipv 1 score mate 3 nodes 1211911 nps 44885592 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552654] <     139680131787568> Stockfish ---> info depth 229 seldepth 6 multipv 1 score mate 3 nodes 1211931 nps 44886333 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552665] <     139680131787568> Stockfish ---> info depth 230 seldepth 6 multipv 1 score mate 3 nodes 1211951 nps 44887074 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552675] <     139680131787568> Stockfish ---> info depth 231 seldepth 6 multipv 1 score mate 3 nodes 1211971 nps 44887814 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552686] <     139680131787568> Stockfish ---> info depth 232 seldepth 6 multipv 1 score mate 3 nodes 1211991 nps 44888555 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552697] <     139680131787568> Stockfish ---> info depth 233 seldepth 6 multipv 1 score mate 3 nodes 1212011 nps 44889296 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552707] <     139680131787568> Stockfish ---> info depth 234 seldepth 6 multipv 1 score mate 3 nodes 1212031 nps 44890037 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552718] <     139680131787568> Stockfish ---> info depth 235 seldepth 6 multipv 1 score mate 3 nodes 1212051 nps 44890777 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552729] <     139680131787568> Stockfish ---> info depth 236 seldepth 6 multipv 1 score mate 3 nodes 1212071 nps 44891518 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552739] <     139680131787568> Stockfish ---> info depth 237 seldepth 6 multipv 1 score mate 3 nodes 1212091 nps 44892259 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552750] <     139680131787568> Stockfish ---> info depth 238 seldepth 6 multipv 1 score mate 3 nodes 1212111 nps 44893000 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552760] <     139680131787568> Stockfish ---> info depth 239 seldepth 6 multipv 1 score mate 3 nodes 1212131 nps 44893740 hashfull 2 tbhits 18 time 27 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552771] <     139680131787568> Stockfish ---> info depth 240 seldepth 6 multipv 1 score mate 3 nodes 1212151 nps 43291107 hashfull 2 tbhits 18 time 28 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552782] <     139680131787568> Stockfish ---> info depth 241 seldepth 6 multipv 1 score mate 3 nodes 1212171 nps 43291821 hashfull 2 tbhits 18 time 28 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552793] <     139680131787568> Stockfish ---> info depth 242 seldepth 6 multipv 1 score mate 3 nodes 1212191 nps 43292535 hashfull 2 tbhits 18 time 28 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552803] <     139680131787568> Stockfish ---> info depth 243 seldepth 6 multipv 1 score mate 3 nodes 1212211 nps 43293250 hashfull 2 tbhits 18 time 28 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552814] <     139680131787568> Stockfish ---> info depth 244 seldepth 6 multipv 1 score mate 3 nodes 1212231 nps 43293964 hashfull 2 tbhits 18 time 28 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552827] <     139680131787568> Stockfish ---> info depth 245 seldepth 6 multipv 1 score mate 3 nodes 1212251 nps 43294678 hashfull 2 tbhits 18 time 28 pv a4b3 a1b1 b5c5 b1a1 c5c1
[Engine] [13:30:40.552837] <     139680131787568> Stockfish ---> bestmove a4b3 ponder a1b1
[TRACE ] [13:30:40.552854] <     139680131787568> fastchess --- Engine Stockfish is done thinking
[TRACE ] [13:30:40.552867] <     139680131787568> fastchess --- Check if engine Stockfish is in a ready state
[TRACE ] [13:30:40.552885] <     139680131787568> fastchess --- Pinging engine Stockfish
[Engine] [13:30:40.552896] <     139680131787568> Stockfish <--- isready
[Engine] [13:30:40.553384] <     139680131787568> Stockfish ---> readyok
[TRACE ] [13:30:40.553399] <     139680131787568> fastchess --- Engine Stockfish is responsive
[TRACE ] [13:30:40.553407] <     139680131787568> fastchess --- Engine Stockfish is in a ready state
[WARN  ] [13:30:40.554566] <     139680131787568> fastchess --- Engine Stockfish loses on time
[Engine] [13:30:40.554575] <     139680131787568> Stockfish <--- stop
[TRACE ] [13:30:40.554639] <     139680131787568> fastchess --- Game 1 between Stockfish and Lc0 finished

[dav1312]

Scanned all 250 game logs against Plenty and against Leela.
Flagged if difference between real time and engine time was over 0.25s

ccc_scan_log_time_inconsistency_plenty.txt

• Plenty: 16 cases
• Stockfish: 19 cases

ccc_scan_log_time_inconsistency_lc0.txt

• Lc0: 2 cases
• Stockfish: 257 cases (210 of them Stockfish said it spent 0ms)

There are cases that the script didn't detect like this one in game-192799. Stockfish "spends" 1.600025 seconds between messages.

[Engine] [10:37:40.588472] <     140360002689840> Stockfish ---> info string Network replica 3: Local memory. Shared memory not supported by the OS. Local allocation fallback.
[Engine] [10:37:42.188497] <     140360002689840> Stockfish ---> info string Network replica 4: Local memory. Shared memory not supported by the OS. Local allocation fallback.