Bugs in CPW and Schola

[Guenther Simon]

I found that the newest version of CPW 050908JA here uses 50% of the
opponents CPU under WB with ponder off on my P4!
(I don't know if this happens also for ealier releases, because I didn't test
them)

Schola 1.04 once did not accept a correct book move from the opponent?
(May be a book error in Schola?)

Regards,
Guenther

From Scholas log:

Code: Select all

INFO  08-11-10 15.30.22 : Schola Logging initialized for pid 3496
INFO  08-11-10 15.30.22 : Reading hashcodes.bin
INFO  08-11-10 15.30.23 : HashTable created 13421772 elements, size 256.00Mb (each 20)
INFO  08-11-10 15.30.23 : Using tournament book tbclassc.bin, main book super.bin
INPUT 08-11-10 15.30.23 : xboard
OUTPUT 08-11-10 15.30.23 :
INPUT 08-11-10 15.30.23 : protover 2
OUTPUT 08-11-10 15.30.23 : feature myname="Schola 1.0.4 by Andy Platt"
OUTPUT 08-11-10 15.30.23 : feature variants="normal"
OUTPUT 08-11-10 15.30.23 : feature setboard=1
OUTPUT 08-11-10 15.30.23 : feature san=1
OUTPUT 08-11-10 15.30.23 : feature colors=0
OUTPUT 08-11-10 15.30.23 : feature playother=1
OUTPUT 08-11-10 15.30.23 : feature ping=1
OUTPUT 08-11-10 15.30.23 : feature time=1
OUTPUT 08-11-10 15.30.23 : feature name=1
OUTPUT 08-11-10 15.30.23 : feature analyze=1
OUTPUT 08-11-10 15.30.23 : feature done=1
INPUT 08-11-10 15.30.23 : accepted myname
INPUT 08-11-10 15.30.23 : accepted variants
INPUT 08-11-10 15.30.23 : accepted setboard
INPUT 08-11-10 15.30.23 : accepted san
INPUT 08-11-10 15.30.23 : accepted colors
INPUT 08-11-10 15.30.23 : accepted playother
INPUT 08-11-10 15.30.23 : accepted ping
INPUT 08-11-10 15.30.23 : accepted time
INPUT 08-11-10 15.30.23 : accepted name
INPUT 08-11-10 15.30.23 : accepted analyze
INPUT 08-11-10 15.30.23 : accepted done
INPUT 08-11-10 15.30.23 : new
INPUT 08-11-10 15.30.23 : random
INPUT 08-11-10 15.30.23 : level 40 15 0
INFO  08-11-10 15.30.23 : Set TimeControl to 40 moves in 900 seconds (increment 0 seconds)
INPUT 08-11-10 15.30.23 : post
INPUT 08-11-10 15.30.23 : hard
INPUT 08-11-10 15.30.23 : easy
INPUT 08-11-10 15.30.23 : ping 1
INPUT 08-11-10 15.30.23 : force
INPUT 08-11-10 15.30.23 : computer
INPUT 08-11-10 15.30.23 : name Ghost_014.exe
INPUT 08-11-10 15.30.23 : time 90000
INFO  08-11-10 15.30.23 : Set Remaining Time to 900000
INPUT 08-11-10 15.30.23 : otim 89999
INPUT 08-11-10 15.30.23 : e4
INFO  08-11-10 15.30.23 : Played move: 1. e4
INFO  08-11-10 15.30.23 : New Position: rnbqkbnr/pppppppp/8/8/4P3/8/PPPP1PPP/RNBQKBNR b KQkq e3 0 1
INPUT 08-11-10 15.30.23 : go
OUTPUT 08-11-10 15.30.23 : pong 1
INFO  08-11-10 15.30.23 : StartMove: Move time allocated 22487
INFO  08-11-10 15.30.23 : Playing Book Move e5
OUTPUT 08-11-10 15.30.23 : 0      0       0      0  (BOOK e5)
OUTPUT 08-11-10 15.30.23 : move e5
INFO  08-11-10 15.30.23 : StopMove: Moves Remaining to control 39, remaining time 900000
INFO  08-11-10 15.30.23 : Played move: 1... e5
INFO  08-11-10 15.30.23 : New Position: rnbqkbnr/pppp1ppp/8/4p3/4P3/8/PPPP1PPP/RNBQKBNR w KQkq e6 0 2
INPUT 08-11-10 15.30.23 : time 89990
INFO  08-11-10 15.30.23 : Set Remaining Time to 899900
INPUT 08-11-10 15.30.23 : otim 89997
INPUT 08-11-10 15.30.23 : Nf3
INFO  08-11-10 15.30.23 : Played move: 2. Nf3
INFO  08-11-10 15.30.23 : New Position: rnbqkbnr/pppp1ppp/8/4p3/4P3/5N2/PPPP1PPP/RNBQKB1R b KQkq - 1 2
INFO  08-11-10 15.30.23 : StartMove: Move time allocated 23061
INFO  08-11-10 15.30.23 : Playing Book Move Nc6
OUTPUT 08-11-10 15.30.23 : 0      0       0      0  (BOOK Nc6)
OUTPUT 08-11-10 15.30.23 : move Nc6
INFO  08-11-10 15.30.23 : StopMove: Moves Remaining to control 38, remaining time 899900
INFO  08-11-10 15.30.23 : Played move: 2... Nc6
INFO  08-11-10 15.30.23 : New Position: r1bqkbnr/pppp1ppp/2n5/4p3/4P3/5N2/PPPP1PPP/RNBQKB1R w KQkq - 2 3
INPUT 08-11-10 15.30.23 : time 89986
INFO  08-11-10 15.30.23 : Set Remaining Time to 899860
INPUT 08-11-10 15.30.23 : otim 89996
INPUT 08-11-10 15.30.23 : Bb5
INFO  08-11-10 15.30.23 : Played move: 3. Bb5
INFO  08-11-10 15.30.23 : New Position: r1bqkbnr/pppp1ppp/2n5/1B2p3/4P3/5N2/PPPP1PPP/RNBQK2R b KQkq - 3 3
INFO  08-11-10 15.30.23 : StartMove: Move time allocated 23667
INFO  08-11-10 15.30.23 : Playing Book Move a6
OUTPUT 08-11-10 15.30.23 : 0      0       0      0  (BOOK a6)
OUTPUT 08-11-10 15.30.23 : move a6
INFO  08-11-10 15.30.23 : StopMove: Moves Remaining to control 37, remaining time 899860
INFO  08-11-10 15.30.23 : Played move: 3... a6
INPUT 08-11-10 15.30.24 : time 89981
INFO  08-11-10 15.30.24 : Set Remaining Time to 899810
INPUT 08-11-10 15.30.24 : otim 89994
INPUT 08-11-10 15.30.24 : Ba4
OUTPUT 08-11-10 15.30.24 : Error (unknown command): Ba4
INFO  08-11-10 15.30.24 : New Position: r1bqkbnr/1ppp1ppp/p1n5/1B2p3/4P3/5N2/PPPP1PPP/RNBQK2R w KQkq - 0 4
INPUT 08-11-10 15.45.23 : result 1-0 {White wins on time}
INPUT 08-11-10 15.45.24 : quit

[plattyaj]

Another reply to a very old post.

Straightforward bug ... probably seen before but freshly looking at this piece of code shows it straight away. The move is reported to the user (in this case the GUI) before it's actually made in internal copy of the game data structures. Ooops - timing window, because now if the user can respond in time it, it will check the move's validity against the old version. In fact the logging showed this up pretty easily:

Code: Select all

INFO  08-11-10 15.30.23 : Played move: 3... a6
...
INPUT 08-11-10 15.30.24 : Ba4
OUTPUT 08-11-10 15.30.24 : Error (unknown command): Ba4
INFO  08-11-10 15.30.24 : New Position: r1bqkbnr/1ppp1ppp/p1n5/1B2p3/4P3/5N2/PPPP1PPP/RNBQK2R w KQkq - 0 4


That last INFO line showing the "new position" was actually the position from 3... a6.

In reality all of the code that deals with the Winboard - engine interface should have been redone a while back

I haven't been too active recently. I'll see if I can fix this and a couple of other things up.

Andy.