Geschrieben von:/Posted by: Volker Pittlik at 29 March 2004 21:53:38:
Als Antwort auf:/In reply to: Gothmog 0.4.7 winboard protocol problem? (ctd) geschrieben von:/posted by: Robert Allgeuer at 29 March 2004 21:34:01:
I start a new thread because the previous one has gone a bit out of bounds.
Thanks for the explanations, so it is a race condition. However, on my computer this race condition hits each and every time I run Gothmog.
I looked at the debug output of other engines on the same hardware
Look at the debug output below (hit reply button to see the correct text); also Crafty 18.12 does not send feature done=0, there is also the same race condition, Crafty also gets new, level, black, go etc. all before CRafty even has sent the first feature, but apparently Crafty nevertheless handles this correctly and plays as black and not as white.
Robert
>recognized 'normal' (-1) as variant normal
>WinBoard 4.2.7 + Ruffian
>Reset(1, 0) from gameMode 0
>recognized 'normal' (-1) as variant normal
>GameEnds(0, (null), 2)
>StartChildProcess (dir="f:\chess\ruffian") Ruffian
>701 >first : xboard
>protover 2
>731 <first : Ruffian 1.0.1
>731 <first : feature done=0
>731 >first : accepted done
>731 <first : Loading configuration: ruffian.cfg
>731 <first : Warning: RESIGN changed, default=700, newvalue=900
>731 <first : Warning: HASH changed, default=33554432, newvalue=67108864
>731 <first : Warning: EGTB_HASH_SIZE changed, default=1048576, newvalue=6291456
>731 <first : Logfile: Mon Mar 29 21:11:28 2004
>731 <first : Total hash size 64M
>731 <first : Open book: ruffian.bok
>731 <first : Book version 0.3 created Sun Sep 29 02:01:08 2002
>731 <first : 59116 book moves, depth=60
>731 <first : ruffian: [xboard]
>7300 <first : feature setboard=1
>7300 >first : accepted setboard
>7300 <first : feature analyze=1
>7300 >first : accepted analyze
>7300 <first : feature setboard=1
>7300 >first : accepted setboard
>7300 <first : feature myname="Ruffian 1.0.1"
>7300 >first : accepted myname
>7300 <first : feature done=1
>7300 >first : accepted done
>7300 <first : feature ping=1
>7300 >first : accepted ping
>7310 >first : new
>random
>7310 >first : level 0 5 2
>7310 >first : post
>7310 >first : hard
>7310 >first : easy
>7310 >first : ping 1
>7310 <first : pong 1
>13800 >first : force
>StartChildProcess (dir="f:\chess\crafty") Crafty xboard
>14351 >second: xboard
>protover 2
>14351 <second: EPD Kit revision date: 1996.04.21
>14351 <second: 
>14351 <second: tellicsnoalias set 1 Crafty v18.12
>14351 <second: tellicsnoalias kibitz Hello from Crafty v18.12!
>14351 <second: hash table memory = 48M bytes.
>14351 <second: pawn hash table memory = 5M bytes.
>14351 <second: EGTB cache memory = 8M bytes.
>14371 <second: draw score set to 0.00 pawns.
>14371 <second: choose from book moves randomly (using weights.)
>14371 <second: choose from 5 best moves.
>14371 <second: book learning enabled
>14371 <second: result learning enabled
>14371 <second: position learning enabled
>24355 >second: new
>random
>24355 >second: level 0 5 2
>24355 >second: post
>24355 >second: hard
>24355 >second: easy
>24355 >second: force
>24355 >first : computer
>24355 >second: computer
>24355 >first : black
>24355 >first : time 30000
>otim 30000
>24355 >first : white
>24355 >first : go
>24505 <first : move g1f3
>24505 >second: time 30000
>otim 30185
>24505 >second: g1f3
>24505 >second: black
>24505 >second: go
>27099 <second: feature ping=1 setboard=1 san=1 time=1 draw=1
>Interrupting second
>27099 >second: accepted ping
>27099 >second: accepted setboard
>27099 >second: accepted san
>27099 >second: accepted time
>27099 >second: accepted draw
>27099 <second: feature sigint=0 sigterm=0 reuse=1 analyze=1
>27099 >second: accepted sigint
>27099 >second: accepted sigterm
>27099 >second: accepted reuse
>27099 >second: accepted analyze
>27099 <second: feature myname="Crafty-18.12" name=1
>27099 >second: accepted myname
>27099 >second: accepted name
>27099 <second: feature playother=1 colors=0
>27099 >second: accepted playother
>27099 >second: accepted colors
>27099 <second: variants="normal,nocastle"
>27099 <second: done=1
>27309 <second: tellicsnoalias set 1 Crafty v18.12 (1 cpus)
>27309 <second: game/5 minutes primary time control
>27309 <second: increment 2 seconds.
>27309 <second: pondering enabled.
>27309 <second: pondering disabled.
>27640 <second: move Nf6
>Interrupting first
>27640 >first : time 30185
>otim 29886
>27640 >first : g8f6
>27730 <first : move c2c4
>27740 >second: time 29886
>otim 30376
>27740 >second: c4
>27810 <second: move d6
>Interrupting first
>27820 >first : time 30376
>otim 30078
>27820 >first : d7d6
>27890 <first : move d2d4
>27890 >second: time 30078
>otim 30568
>27890 >second: d4
>27970 <second: move Nbd7
>Interrupting first
>27980 >first : time 30568
>otim 30270
>27980 >first : b8d7
>28071 <first : move b1c3
>28081 >second: time 30270
>otim 30757
>28081 >second: Nc3
>28171 <second: move e5
>Interrupting first
>28181 >first : time 30757
>otim 30459
>28181 >first : e7e5
>28251 <first : move e2e4
>28251 >second: time 30459
>otim 30950
>28261 >second: e4
>28341 <second: move c6
>Interrupting first
>28341 >first : time 30950
>otim 30650
>28341 >first : c7c6
>28411 <first : move f1e2
>28411 >second: time 30650
>otim 31143
>28411 >second: Be2
>28501 <second: move g6
>Interrupting first
>28501 >first : time 31143
>otim 30841
>28501 >first : g7g6
>28571 <first : move e1g1
>28571 >second: time 30841
>otim 31336
>28581 >second: O-O
>28651 <second: move Bg7
>Interrupting first
>28661 >first : time 31336
>otim 31033
>28661 >first : f8g7
>28731 <first : move f1e1
>28742 >second: time 31033
>otim 31528
>28742 >second: Re1
>29072 <second:   6  44 30 109327 8. ... O-O 9. dxe5 dxe5 10. Qd6 Ne8 11. Qd3 b6
>30124 <second:   7  41  135 575638 8. ... O-O 9. h4 a5 10. Qc2 b6 11. Bg5 Bb7
>30154 <second:   7  41  138 590618 8. ... O-O 9. h4 a5 10. Qc2 b6 11. Bg5 Bb7
>31766 <second:   8  44  299 1366111 8. ... O-O 9. dxe5 dxe5 10. Qd6 a5 11. Rd1 Ne8 12. Qd3 Nc7
>31926 <second:   8  44  316 1451025 8. ... O-O 9. dxe5 dxe5 10. Qd6 a5 11. Rd1 Ne8 12. Qd3 Nc7
>Reset(1, 1) from gameMode 5
>recognized 'normal' (-1) as variant normal
>GameEnds(0, (null), 2)
>32156 >first : force
>32156 >first : ping 2
>32156 >second: force
>32156 >second: ping 1
>32176 >first : new
>random
>32176 >first : level 0 5 2
>32176 >first : post
>32176 >first : hard
>32176 >first : easy
>32176 >first : ping 3
>32207 <first : pong 2
>32207 <first : pong 3
>32767 <second: move O-O
>Ignoring extra move from second
>32767 <second: learning position, wtm=0 value=-44
>32787 <second: pong 1
>GameEnds(0, (null), 2)
>35201 >first : force
>35201 >first : ping 4
>35201 >first : quit
>35201 >second: quit
>for comparison Gothmog:
>
>recognized 'normal' (-1) as variant normal
>WinBoard 4.2.7 + Gothmog
>Reset(1, 0) from gameMode 0
>recognized 'normal' (-1) as variant normal
>GameEnds(0, (null), 2)
>StartChildProcess (dir="f:\chess\gothmog_0.4.7sel4") Gothmog
>591 >first : xboard
>protover 2
>10595 >first : new
>random
>10595 >first : level 0 5 2
>10595 >first : post
>10595 >first : hard
>10595 >first : easy
>19348 >first : force
>StartChildProcess (dir="f:\chess\gothmog_0.4.7sel6") Gothmog
>19859 >second: xboard
>protover 2
>22943 <first : feature ping=1
>22943 >first : accepted ping
>22943 <first : feature setboard=1
>22943 >first : accepted setboard
>22943 <first : feature san=0
>22943 >first : accepted san
>22943 <first : feature usermove=1
>22943 >first : accepted usermove
>22943 <first : feature time=1
>22943 >first : accepted time
>22943 <first : feature sigint=0
>22943 >first : accepted sigint
>22943 <first : feature sigterm=0
>22943 >first : accepted sigterm
>22943 <first : feature reuse=1
>22953 >first : accepted reuse
>22963 <first : feature analyze=1
>22973 >first : accepted analyze
>22973 <first : feature i&