engine takes time to load... while clock is ticking down

[EdCollins]

When I FIRST start Winboard with command-line parameters for an engine-engine tourney, the first engine is handicapped by about five seconds. It seems to take that long for the engine to fully load, all while its clock is ticking down.

For long time controls this is not a big problem, but for one minute bullet games, giving odds of five or six seconds is a big handicap. What's the best remedy to this?

[H.G.Muller]

Which engine is this? It soundslike an engine problem. I don't think that WiBoard starts the clocks before both engnes have sent feature done=1 to WinBoard. If they stall after that, it is ther own problem.

It would help if you could post the initial part of the winboard.debug file (upto the first move) of a game with an engine that does this. (Start WinBoard with additional option /debug .)

[EdCollins]

As always, thanks in advance for your help.

The version of WinBoard is 4.5.1. The engine is Deep Rybka 4. As soon as I can, I can do further tests on other engines.

Here is a copy of the command line string, but I think this is irrelevant:

/cp /tc 1 /inc 0 /mg 1 /fcp "polyglot1466b _PG/houdini.ini" /scp "polyglot1466b _PG/rybka4.ini" /sgf match_results.txt /debug

And below is a copy of the first portion of the debug file. (I hope it's not too long to post in this forum.)

Much of this debug file is understandable to me, but some of it is not. Am I reading it correctly? Only 55.48 seconds (from 60) remain before/when Black makes its first move? (I'm guessing that "otim" probably means Opponent's time?) It looks like "feature done = 1" is sent by both engines. So yes, it most certainly is probably an engine problem.

Note: Last night, when attempting to duplicate this problem on my home computer (I'm currently experiencing this problem using a work laptop) I don't believe I had a problem! But then again, I'm using a different version of Rybka and a different version of Winboard so the conditions are not the same.) Obviously, further testing is in order to fully isolate what is wrong, with whom, and exactly when. However, below is the first few lines of the WinBoard debug file:

[size=85]recognized 'normal' (-1) as variant normal
recognized 'normal' (-1) as variant normal
WinBoard 4.5.1 + polyglot1466b
Reset(1, 0) from gameMode 0
recognized 'normal' (-1) as variant normal
GameEnds(0, (null), 2)
shuffleOpenings = 0
TC string = ':60'
mps=0 tc=60000 inc=0
TC string = ':60'
mps=0 tc=60000 inc=0
StartChildProcess (dir=".") polyglot1466b _PG/houdini.ini
546 >first : xboard
protover 2
546 <first : PolyGlot 1.4.66b by Fabien Letouzey.
546 <first : feature done=0
546 >first : accepted done
546 <first : feature analyze=1
546 >first : accepted analyze
546 <first : feature colors=0
546 >first : accepted colors
546 <first : feature draw=1
546 >first : accepted draw
546 <first : feature ics=1
546 >first : accepted ics
546 <first : feature myname="Houdini 1.5"
546 >first : accepted myname
546 <first : feature name=1
546 >first : accepted name
546 <first : feature pause=0
546 >first : accepted pause
546 <first : feature ping=1
546 >first : accepted ping
546 <first : feature playother=1
546 >first : accepted playother
546 <first : feature sigint=1
546 >first : accepted sigint
546 <first : feature reuse=1
546 >first : accepted reuse
546 <first : feature san=0
546 >first : accepted san
546 <first : feature setboard=1
546 >first : accepted setboard
546 <first : feature sigint=0
546 >first : accepted sigint
546 <first : feature sigterm=0
546 >first : accepted sigterm
546 <first : feature time=1
546 >first : accepted time
546 <first : feature usermove=1
546 >first : accepted usermove
546 <first : feature nps=1
546 >first : accepted nps
546 <first : feature memory=1
546 >first : accepted memory
546 <first : feature smp=1
546 >first : accepted smp
546 <first : feature egt="gaviota"
546 >first : accepted egt
546 <first : feature variants="normal"
546 >first : accepted variants
546 <first : feature option="Clear Hash -button"
546 >first : accepted option
546 <first : feature option="Split_Depth -spin 10 8 99"
546 >first : accepted option
546 <first : feature option="Contempt -spin 1 0 2"
546 >first : accepted option
546 <first : feature option="Analysis_Contempt -check 0"
546 >first : accepted option
546 <first : feature option="MultiPV -spin 1 1 16"
562 >first : accepted option
562 <first : feature option="GaviotaTbCache -spin 64 4 1024"
562 >first : accepted option
562 <first : feature option="Hard_Probe_Depth -spin 24 2 99"
562 >first : accepted option
562 <first : feature option="Soft_Probe_Depth -spin 16 2 99"
562 >first : accepted option
562 <first : feature option="Polyglot SettingsFile -file _PG/houdini.ini"
562 >first : accepted option
562 <first : feature option="Polyglot Log -check 0"
562 >first : accepted option
562 <first : feature option="Polyglot LogFile -file polyglot.log"
562 >first : accepted option
562 <first : feature option="Polyglot Resign -check 0"
562 >first : accepted option
562 <first : feature option="Polyglot ResignMoves -spin 3 0 10000"
562 >first : accepted option
562 <first : feature option="Polyglot ResignScore -spin 800 0 10000"
562 >first : accepted option
562 <first : feature option="Polyglot Book -check 1"
562 >first : accepted option
562 <first : feature option="Polyglot BookFile -file default_book.bin"
562 >first : accepted option
562 <first : feature option="Polyglot BookRandom -check 1"
562 >first : accepted option
562 <first : feature option="Polyglot BookDepth -spin 256 0 256"
562 >first : accepted option
562 <first : feature option="Polyglot STFudge -spin 20 0 1000"
562 >first : accepted option
562 <first : feature option="Polyglot SyncStop -check 0"
562 >first : accepted option
562 <first : feature option="Polyglot Save -save"
562 >first : accepted option
562 <first : feature done=1
562 >first : accepted done
577 >first : memory 2052
577 >first : cores 1
577 >first : new
random
577 >first : ics -
577 >first : level 0 1 0
577 >first : post
577 >first : hard
577 >first : easy
577 >first : ping 1
577 >first : force
StartChildProcess (dir=".") polyglot1466b _PG/rybka4.ini
1092 >second: xboard
protover 2
1108 <second: PolyGlot 1.4.66b by Fabien Letouzey.
1108 <second: feature done=0
1108 >second: accepted done
1108 <second: feature analyze=1
1108 >second: accepted analyze
1108 <second: feature colors=0
1108 >second: accepted colors
1108 <second: feature draw=1
1108 >second: accepted draw
1108 <second: feature ics=1
1108 >second: accepted ics
1108 <second: feature myname="Deep Rybka 4"
1108 >second: accepted myname
1108 <second: feature name=1
1108 >second: accepted name
1108 <second: feature pause=0
1108 >second: accepted pause
1108 <second: feature ping=1
1108 >second: accepted ping
1108 <second: feature playother=1
1108 >second: accepted playother
1108 <second: feature sigint=1
1108 >second: accepted sigint
1108 <second: feature reuse=1
1108 >second: accepted reuse
1108 <second: feature san=0
1108 >second: accepted san
1108 <second: feature setboard=1
1108 >second: accepted setboard
1108 <second: feature sigint=0
1108 >second: accepted sigint
1108 <second: feature sigterm=0
1108 >second: accepted sigterm
1108 <second: feature time=1
1108 >second: accepted time
1108 <second: feature usermove=1
1108 >second: accepted usermove
1108 <second: feature nps=1
1108 >second: accepted nps
1108 <second: feature memory=1
1108 >second: accepted memory
1108 <second: feature smp=1
1108 >second: accepted smp
1108 <second: feature egt="nalimov"
1108 >second: accepted egt
1108 <second: feature variants="normal"
1108 >second: accepted variants
1108 <second: feature option="UCI_Query -button"
1108 >second: accepted option
1108 <second: feature option="Log -check 0"
1108 >second: accepted option
1108 <second: feature option="xLog -check 0"
1108 >second: accepted option
1108 <second: feature option="CPU Usage -spin 100 1 100"
1108 >second: accepted option
1108 <second: feature option="NalimovCache -spin 1 1 256"
1108 >second: accepted option
1108 <second: feature option="NalimovUsage -combo Frequently /// Normally /// *Rarely /// Never"
1108 >second: accepted option
1108 <second: feature option="Main Process Priority -combo Normal /// BelowNormal /// Low /// *Inherit"
1108 >second: accepted option
1108 <second: feature option="Child Process Priority -combo Normal /// *BelowNormal /// Low"
1108 >second: accepted option
1108 <second: feature option="Use Large Pages -check 0"
1108 >second: accepted option
1108 <second: feature option="Always Score Main Move -check 0"
1108 >second: accepted option
1108 <second: feature option="Display Upperbounds -check 0"
1108 >second: accepted option
1108 <second: feature option="Preserve Analysis -check 0"
1108 >second: accepted option
1108 <second: feature option="Clear Hash -button"
1108 >second: accepted option
1108 <second: feature option="Hash File -file <empty>"
1108 >second: accepted option
1108 <second: feature option="Save Hash -button"
1108 >second: accepted option
1108 <second: feature option="Load Hash -button"
1108 >second: accepted option
1108 <second: feature option="MultiPV -spin 1 1 256"
1108 >second: accepted option
1108 <second: feature option="MultiPV_cp -spin 20000 0 20000"
1108 >second: accepted option
1108 <second: feature option="TC Buffer -spin 3 0 120"
1108 >second: accepted option
1108 <second: feature option="TC Normal Move Time -spin 80 1 10000"
1108 >second: accepted option
1108 <second: feature option="TC Max Move Time -spin 100 1 10000"
1108 >second: accepted option
1108 <second: feature option="White Pawn cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black Pawn cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="White Knight cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black Knight cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="White King Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black King Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="White Queen Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black Queen Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1123 <second: feature option="White Rook cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="Black Rook cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="White Queen cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="Black Queen cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="Rook Endgame Scaling -spin 100 0 1000"
1123 >second: accepted option
1123 <second: feature option="UCI_LimitStrength -check 0"
1123 >second: accepted option
1123 <second: feature option="UCI_Elo -spin 1200 1200 2400"
1123 >second: accepted option
1123 <second: feature option="UCI_EngineAbout -string http://www.rybkachess.com"
1123 >second: accepted option
1123 <second: feature option="Polyglot SettingsFile -file _PG/rybka4.ini"
1123 >second: accepted option
1123 <second: feature option="Polyglot Log -check 0"
1123 >second: accepted option
1123 <second: feature option="Polyglot LogFile -file polyglot.log"
1123 >second: accepted option
1123 <second: feature option="Polyglot Resign -check 0"
1123 >second: accepted option
1123 <second: feature option="Polyglot ResignMoves -spin 3 0 10000"
1123 >second: accepted option
1123 <second: feature option="Polyglot ResignScore -spin 800 0 10000"
1123 >second: accepted option
1123 <second: feature option="Polyglot Book -check 1"
1123 >second: accepted option
1123 <second: feature option="Polyglot BookFile -file default_book.bin"
1123 >second: accepted option
1123 <second: feature option="Polyglot BookRandom -check 1"
1123 >second: accepted option
1123 <second: feature option="Polyglot BookDepth -spin 256 0 256"
1123 >second: accepted option
1123 <second: feature option="Polyglot STFudge -spin 20 0 1000"
1123 >second: accepted option
1123 <second: feature option="Polyglot SyncStop -check 0"
1123 >second: accepted option
1123 <second: feature option="Polyglot Save -save"
1123 >second: accepted option
1123 <second: feature done=1
1123 >second: accepted done
1794 <first : pong 1
1810 >second: memory 2052
1810 >second: egtpath nalimov ..\egtb
1810 >second: cores 1
1810 >second: new
random
1810 >second: ics -
1810 >second: level 0 1 0
1810 >second: post
1810 >second: hard
1810 >second: easy
1810 >second: ping 1
1810 >second: force
1810 >first : computer
1810 >first : name Deep Rybka 4
1810 >second: computer
1810 >second: name Houdini 1.5
TC string = ':60'
mps=0 tc=60000 inc=0
TC string = ':60'
mps=0 tc=60000 inc=0
time odds: 1.000000 1.000000
1810 >first : time 6000
1810 >first : otim 6000
book hit = (NULL)
1810 >first : go
nps: w=-1, b=-1
1810 <first : move d2d4
machine move 0, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? d2d4
7 0 4 7 0 4 Legality test? d2d4
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=0 (3,1)-(3,3) -
7 0 4 7 0 4 Legality test? d2d4
movetype=21, promochar=0=-
MateTest: K=1, my=16, his=16
move: d2d4
, parse: d4 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=1 bmm=0 ep=-4, reps=4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
1810 >second: time 6000
1810 >second: otim 6000
book hit = (NULL)
1810 >second: usermove 1810 >second: d2d4
1810 >second: go
6329 <second: pong 1
6329 <second: move g8f6
machine move 1, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? g8f6
7 0 4 7 0 4 Legality test? g8f6
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=23 (6,7)-(5,5) -
MateTest: K=1, my=16, his=16
move: g8f6
, parse: Nf6 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=2 bmm=0 ep=-4, reps=4
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6329 >first : time 6000
6329 >first : otim 5548
book hit = (NULL)
6329 >first : usermove 6329 >first : g8f6
6610 <first : move c2c4
machine move 2, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? c2c4
7 0 4 7 0 4 Legality test? c2c4
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=0 (2,1)-(2,3) -
7 0 4 7 0 4 Legality test? c2c4
movetype=21, promochar=0=-
MateTest: K=1, my=16, his=16
move: c2c4
, parse: c4 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=3 bmm=0 ep=-4, reps=4
3 ep=-3
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6610 >second: time 5548
6610 >second: otim 5971
book hit = (NULL)
6610 >second: usermove 6610 >second: c2c4
6775 <second: move g7g6
machine move 3, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? g7g6
7 0 4 7 0 4 Legality test? g7g6
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=22 (6,6)-(6,5) -
7 0 4 7 0 4 Legality test? g7g6
movetype=21, promochar=0=-
MateTest: K=1, my=16, his=16
move: g7g6
, parse: g6 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=4 bmm=0 ep=-4, reps=4
4 ep=-3
3 ep=-3
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6775 >first : time 5971
6775 >first : otim 5531
book hit = (NULL)
6775 >first : usermove 6775 >first : g7g6
6922 <first : move b1c3
machine move 4, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? b1c3
7 0 4 7 0 4 Legality test? b1c3
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=1 (1,0)-(2,2) -
MateTest: K=1, my=16, his=16
move: b1c3
, parse: Nc3 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=5 bmm=0 ep=-4, reps=4
5 ep=-4
4 ep=-3
3 ep=-3
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6932 >second: time 5531
6932 >second: otim 5957
book hit = (NULL)
6932 >second: usermove 6932 >second: b1c3
7125 <second: move f8g7
machine move 5, castling = 7 0 4 7 0 4[/size]

[EdCollins]

Whoops. That debut font is smaller than I wanted. Please cut and paste into another document to read it.

[matematiko]

Cut and paste here:

Code: Select all

recognized 'normal' (-1) as variant normal
recognized 'normal' (-1) as variant normal
WinBoard 4.5.1 + polyglot1466b
Reset(1, 0) from gameMode 0
recognized 'normal' (-1) as variant normal
GameEnds(0, (null), 2)
shuffleOpenings = 0
TC string = ':60'
mps=0 tc=60000 inc=0
TC string = ':60'
mps=0 tc=60000 inc=0
StartChildProcess (dir=".") polyglot1466b _PG/houdini.ini
546 >first : xboard
protover 2
546 <first : PolyGlot 1.4.66b by Fabien Letouzey.
546 <first : feature done=0
546 >first : accepted done
546 <first : feature analyze=1
546 >first : accepted analyze
546 <first : feature colors=0
546 >first : accepted colors
546 <first : feature draw=1
546 >first : accepted draw
546 <first : feature ics=1
546 >first : accepted ics
546 <first : feature myname="Houdini 1.5"
546 >first : accepted myname
546 <first : feature name=1
546 >first : accepted name
546 <first : feature pause=0
546 >first : accepted pause
546 <first : feature ping=1
546 >first : accepted ping
546 <first : feature playother=1
546 >first : accepted playother
546 <first : feature sigint=1
546 >first : accepted sigint
546 <first : feature reuse=1
546 >first : accepted reuse
546 <first : feature san=0
546 >first : accepted san
546 <first : feature setboard=1
546 >first : accepted setboard
546 <first : feature sigint=0
546 >first : accepted sigint
546 <first : feature sigterm=0
546 >first : accepted sigterm
546 <first : feature time=1
546 >first : accepted time
546 <first : feature usermove=1
546 >first : accepted usermove
546 <first : feature nps=1
546 >first : accepted nps
546 <first : feature memory=1
546 >first : accepted memory
546 <first : feature smp=1
546 >first : accepted smp
546 <first : feature egt="gaviota"
546 >first : accepted egt
546 <first : feature variants="normal"
546 >first : accepted variants
546 <first : feature option="Clear Hash -button"
546 >first : accepted option
546 <first : feature option="Split_Depth -spin 10 8 99"
546 >first : accepted option
546 <first : feature option="Contempt -spin 1 0 2"
546 >first : accepted option
546 <first : feature option="Analysis_Contempt -check 0"
546 >first : accepted option
546 <first : feature option="MultiPV -spin 1 1 16"
562 >first : accepted option
562 <first : feature option="GaviotaTbCache -spin 64 4 1024"
562 >first : accepted option
562 <first : feature option="Hard_Probe_Depth -spin 24 2 99"
562 >first : accepted option
562 <first : feature option="Soft_Probe_Depth -spin 16 2 99"
562 >first : accepted option
562 <first : feature option="Polyglot SettingsFile -file _PG/houdini.ini"
562 >first : accepted option
562 <first : feature option="Polyglot Log -check 0"
562 >first : accepted option
562 <first : feature option="Polyglot LogFile -file polyglot.log"
562 >first : accepted option
562 <first : feature option="Polyglot Resign -check 0"
562 >first : accepted option
562 <first : feature option="Polyglot ResignMoves -spin 3 0 10000"
562 >first : accepted option
562 <first : feature option="Polyglot ResignScore -spin 800 0 10000"
562 >first : accepted option
562 <first : feature option="Polyglot Book -check 1"
562 >first : accepted option
562 <first : feature option="Polyglot BookFile -file default_book.bin"
562 >first : accepted option
562 <first : feature option="Polyglot BookRandom -check 1"
562 >first : accepted option
562 <first : feature option="Polyglot BookDepth -spin 256 0 256"
562 >first : accepted option
562 <first : feature option="Polyglot STFudge -spin 20 0 1000"
562 >first : accepted option
562 <first : feature option="Polyglot SyncStop -check 0"
562 >first : accepted option
562 <first : feature option="Polyglot Save -save"
562 >first : accepted option
562 <first : feature done=1
562 >first : accepted done
577 >first : memory 2052
577 >first : cores 1
577 >first : new
random
577 >first : ics -
577 >first : level 0 1 0
577 >first : post
577 >first : hard
577 >first : easy
577 >first : ping 1
577 >first : force
StartChildProcess (dir=".") polyglot1466b _PG/rybka4.ini
1092 >second: xboard
protover 2
1108 <second: PolyGlot 1.4.66b by Fabien Letouzey.
1108 <second: feature done=0
1108 >second: accepted done
1108 <second: feature analyze=1
1108 >second: accepted analyze
1108 <second: feature colors=0
1108 >second: accepted colors
1108 <second: feature draw=1
1108 >second: accepted draw
1108 <second: feature ics=1
1108 >second: accepted ics
1108 <second: feature myname="Deep Rybka 4"
1108 >second: accepted myname
1108 <second: feature name=1
1108 >second: accepted name
1108 <second: feature pause=0
1108 >second: accepted pause
1108 <second: feature ping=1
1108 >second: accepted ping
1108 <second: feature playother=1
1108 >second: accepted playother
1108 <second: feature sigint=1
1108 >second: accepted sigint
1108 <second: feature reuse=1
1108 >second: accepted reuse
1108 <second: feature san=0
1108 >second: accepted san
1108 <second: feature setboard=1
1108 >second: accepted setboard
1108 <second: feature sigint=0
1108 >second: accepted sigint
1108 <second: feature sigterm=0
1108 >second: accepted sigterm
1108 <second: feature time=1
1108 >second: accepted time
1108 <second: feature usermove=1
1108 >second: accepted usermove
1108 <second: feature nps=1
1108 >second: accepted nps
1108 <second: feature memory=1
1108 >second: accepted memory
1108 <second: feature smp=1
1108 >second: accepted smp
1108 <second: feature egt="nalimov"
1108 >second: accepted egt
1108 <second: feature variants="normal"
1108 >second: accepted variants
1108 <second: feature option="UCI_Query -button"
1108 >second: accepted option
1108 <second: feature option="Log -check 0"
1108 >second: accepted option
1108 <second: feature option="xLog -check 0"
1108 >second: accepted option
1108 <second: feature option="CPU Usage -spin 100 1 100"
1108 >second: accepted option
1108 <second: feature option="NalimovCache -spin 1 1 256"
1108 >second: accepted option
1108 <second: feature option="NalimovUsage -combo Frequently /// Normally /// *Rarely /// Never"
1108 >second: accepted option
1108 <second: feature option="Main Process Priority -combo Normal /// BelowNormal /// Low /// *Inherit"
1108 >second: accepted option
1108 <second: feature option="Child Process Priority -combo Normal /// *BelowNormal /// Low"
1108 >second: accepted option
1108 <second: feature option="Use Large Pages -check 0"
1108 >second: accepted option
1108 <second: feature option="Always Score Main Move -check 0"
1108 >second: accepted option
1108 <second: feature option="Display Upperbounds -check 0"
1108 >second: accepted option
1108 <second: feature option="Preserve Analysis -check 0"
1108 >second: accepted option
1108 <second: feature option="Clear Hash -button"
1108 >second: accepted option
1108 <second: feature option="Hash File -file <empty>"
1108 >second: accepted option
1108 <second: feature option="Save Hash -button"
1108 >second: accepted option
1108 <second: feature option="Load Hash -button"
1108 >second: accepted option
1108 <second: feature option="MultiPV -spin 1 1 256"
1108 >second: accepted option
1108 <second: feature option="MultiPV_cp -spin 20000 0 20000"
1108 >second: accepted option
1108 <second: feature option="TC Buffer -spin 3 0 120"
1108 >second: accepted option
1108 <second: feature option="TC Normal Move Time -spin 80 1 10000"
1108 >second: accepted option
1108 <second: feature option="TC Max Move Time -spin 100 1 10000"
1108 >second: accepted option
1108 <second: feature option="White Pawn cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black Pawn cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="White Knight cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black Knight cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="White King Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black King Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="White Queen Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1108 <second: feature option="Black Queen Bishop cp -spin 0 -1000 1000"
1108 >second: accepted option
1123 <second: feature option="White Rook cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="Black Rook cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="White Queen cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="Black Queen cp -spin 0 -1000 1000"
1123 >second: accepted option
1123 <second: feature option="Rook Endgame Scaling -spin 100 0 1000"
1123 >second: accepted option
1123 <second: feature option="UCI_LimitStrength -check 0"
1123 >second: accepted option
1123 <second: feature option="UCI_Elo -spin 1200 1200 2400"
1123 >second: accepted option
1123 <second: feature option="UCI_EngineAbout -string http://www.rybkachess.com"
1123 >second: accepted option
1123 <second: feature option="Polyglot SettingsFile -file _PG/rybka4.ini"
1123 >second: accepted option
1123 <second: feature option="Polyglot Log -check 0"
1123 >second: accepted option
1123 <second: feature option="Polyglot LogFile -file polyglot.log"
1123 >second: accepted option
1123 <second: feature option="Polyglot Resign -check 0"
1123 >second: accepted option
1123 <second: feature option="Polyglot ResignMoves -spin 3 0 10000"
1123 >second: accepted option
1123 <second: feature option="Polyglot ResignScore -spin 800 0 10000"
1123 >second: accepted option
1123 <second: feature option="Polyglot Book -check 1"
1123 >second: accepted option
1123 <second: feature option="Polyglot BookFile -file default_book.bin"
1123 >second: accepted option
1123 <second: feature option="Polyglot BookRandom -check 1"
1123 >second: accepted option
1123 <second: feature option="Polyglot BookDepth -spin 256 0 256"
1123 >second: accepted option
1123 <second: feature option="Polyglot STFudge -spin 20 0 1000"
1123 >second: accepted option
1123 <second: feature option="Polyglot SyncStop -check 0"
1123 >second: accepted option
1123 <second: feature option="Polyglot Save -save"
1123 >second: accepted option
1123 <second: feature done=1
1123 >second: accepted done
1794 <first : pong 1
1810 >second: memory 2052
1810 >second: egtpath nalimov ..\egtb
1810 >second: cores 1
1810 >second: new
random
1810 >second: ics -
1810 >second: level 0 1 0
1810 >second: post
1810 >second: hard
1810 >second: easy
1810 >second: ping 1
1810 >second: force
1810 >first : computer
1810 >first : name Deep Rybka 4
1810 >second: computer
1810 >second: name Houdini 1.5
TC string = ':60'
mps=0 tc=60000 inc=0
TC string = ':60'
mps=0 tc=60000 inc=0
time odds: 1.000000 1.000000
1810 >first : time 6000
1810 >first : otim 6000
book hit = (NULL)
1810 >first : go
nps: w=-1, b=-1
1810 <first : move d2d4
machine move 0, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? d2d4
7 0 4 7 0 4 Legality test? d2d4
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=0 (3,1)-(3,3) -
7 0 4 7 0 4 Legality test? d2d4
movetype=21, promochar=0=-
MateTest: K=1, my=16, his=16
move: d2d4
, parse: d4 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=1 bmm=0 ep=-4, reps=4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
1810 >second: time 6000
1810 >second: otim 6000
book hit = (NULL)
1810 >second: usermove 1810 >second: d2d4
1810 >second: go
6329 <second: pong 1
6329 <second: move g8f6
machine move 1, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? g8f6
7 0 4 7 0 4 Legality test? g8f6
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=23 (6,7)-(5,5) -
MateTest: K=1, my=16, his=16
move: g8f6
, parse: Nf6 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=2 bmm=0 ep=-4, reps=4
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6329 >first : time 6000
6329 >first : otim 5548
book hit = (NULL)
6329 >first : usermove 6329 >first : g8f6
6610 <first : move c2c4
machine move 2, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? c2c4
7 0 4 7 0 4 Legality test? c2c4
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=0 (2,1)-(2,3) -
7 0 4 7 0 4 Legality test? c2c4
movetype=21, promochar=0=-
MateTest: K=1, my=16, his=16
move: c2c4
, parse: c4 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=3 bmm=0 ep=-4, reps=4
3 ep=-3
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6610 >second: time 5548
6610 >second: otim 5971
book hit = (NULL)
6610 >second: usermove 6610 >second: c2c4
6775 <second: move g7g6
machine move 3, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? g7g6
7 0 4 7 0 4 Legality test? g7g6
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=22 (6,6)-(6,5) -
7 0 4 7 0 4 Legality test? g7g6
movetype=21, promochar=0=-
MateTest: K=1, my=16, his=16
move: g7g6
, parse: g6 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=4 bmm=0 ep=-4, reps=4
4 ep=-3
3 ep=-3
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6775 >first : time 5971
6775 >first : otim 5531
book hit = (NULL)
6775 >first : usermove 6775 >first : g7g6
6922 <first : move b1c3
machine move 4, castling = 7 0 4 7 0 4
7 0 4 7 0 4 Legality test? b1c3
7 0 4 7 0 4 Legality test? b1c3
(7,0) (0,0) (4,0) (7,7) (0,7) (4,7) castling rights
TC string = ':60'
mps=0 tc=60000 inc=0
CoordsToAlgebraic, piece=1 (1,0)-(2,2) -
MateTest: K=1, my=16, his=16
move: b1c3
, parse: Nc3 (
)
MateTest: K=1, my=16, his=16
repeat test fmm=5 bmm=0 ep=-4, reps=4
5 ep=-4
4 ep=-3
3 ep=-3
2 ep=-4
1 ep=-3
0 ep=-4
time odds: 1.000000 1.000000
6932 >second: time 5531
6932 >second: otim 5957
book hit = (NULL)
6932 >second: usermove 6932 >second: b1c3
7125 <second: move f8g7
machine move 5, castling = 7 0 4 7 0 4


[H.G.Muller]

Hmm, this is a bit fishy, indeed. WinBoard seems to send a 'go' command (presumably starting the clock at that time) before it received the 'pong 1' from the second engine. I would have to take a look if this is intended behavior, or a bug. I am not sure WinBoard is supposed to ever wait for a 'pong'. I guess the engine needed the time to grab enough hash memory; this can take time under some conditions. But should that be a reason to not start the clock?

[Teemu Pudas]

577 >first : memory 2052

You're playing 1-minute games with 2GB hash?

[EdCollins]

Teemu, apparently I was, which is odd. Now that I check, almost all of my many other polyglot ini files, for all of my other engines, have my hash set at "Hash = 1024".

[H.G.Muller]

I decided that it is definitely unfair to the second engine not to wait until it is done allocating hash, as recently I added a patch that does make WinBoard wait for the first engine to respond to ping. That was for completely different reasons (to make sure WB would not miss the first engine's reply to the variant command before it initialized the second), but it also has the effect that it waits for the first engine's hash allocation (which happens before the variant command).

So the next version (4.5.2), which we are close to releasing, will have this fixed.