Gödel Chess 3.4.9 WB win32 - Arena 3.5.1 Interface

[Guenther]

--> corrected the header after the edit period of previous post was over.

Gödel does not understand sudden death tc correctly and always uses all time for the first remaining move independent if using a book or not.

[hgm]

Well, there definitely seems to be something wrong with Arena too, because from the time stamps you can see that e2e4 is sent well within 5 min. So Arena cannot think the engine already forfeited on time, and rejects a perfectly legal move without swicthing clocks (and then flags 3 min later). The move e2-e4 is apparently forced by a CECP 'move now' command ('?', showing up as a smiley in the OP).

[Guenther]

Well, there definitely seems to be something wrong with Arena too, because from the time stamps you can see that e2e4 is sent well within 5 min. So Arena cannot think the engine already forfeited on time, and rejects a perfectly legal move without swicthing clocks (and then flags 3 min later). The move e2-e4 is apparently forced by a CECP 'move now' command ('?', showing up as a smiley in the OP).

The last PV seems to appear around 2 minutes into the game, but the (forced?) move e4 appears a bit over 5 minutes after the game was started, if I am not missreading something.
It is a bit funny to see the full wall clock time as measurement in the arena debug.

DEBUG
2017-02-19 09:50:33,649*1*Start calc, move no: 0
2017-02-19 09:50:33,664-->1:level 0 5 0
2017-02-19 09:50:33,664-->1:new
2017-02-19 09:50:33,664-->1:random
2017-02-19 09:50:33,664-->1:level 0 5 0
2017-02-19 09:50:33,664-->1:post
2017-02-19 09:50:33,664-->1:hard
2017-02-19 09:50:33,664-->1:easy
2017-02-19 09:50:33,664-->1:ping 10
2017-02-19 09:50:33,695<--1:pong 10
2017-02-19 09:50:33,711-->1:time 30000
2017-02-19 09:50:33,711-->1:otim 30000
2017-02-19 09:50:33,711-->1:go
2017-02-19 09:50:33,711<--1:move d2d4
2017-02-19 09:50:33,711*1*Zug gefunden:d2-d4
2017-02-19 09:50:36,612*1*Start calc, move no: 2 nearly 3 seconds vanished for nothing??
2017-02-19 09:50:36,674-->1:time 30000 still 300 sec available for White at wall clock 09:50:36,674
2017-02-19 09:50:36,674-->1:otim 29759
2017-02-19 09:50:36,674-->1:usermove a7a6
2017-02-19 09:50:36,674<--1: 6 29 0 10167 Ng1f3
2017-02-19 09:50:36,674<--1: 6 32 0 12497 Ng1f3 Nb8c6 d4d5 Nc6a5 Bc1f4 Na5c4
2017-02-19 09:50:36,674<--1: 6 32 0 14982 Ng1f3 Nb8c6 d4d5 Nc6a5 Bc1f4 Na5c4
2017-02-19 09:50:36,690<--1: 7 33 1 22690 e2e4
2017-02-19 09:50:36,690<--1: 7 44 1 26094 e2e4 Ng8f6 e4e5 Nf6d5 c2c4 Nd5b4 Bc1f4
2017-02-19 09:50:36,690<--1: 7 44 1 28382 e2e4 Ng8f6 e4e5 Nf6d5 c2c4 Nd5b4 Bc1f4
2017-02-19 09:50:36,706<--1: 8 35 3 47130 e2e4 Ng8f6 Nb1c3 Nb8c6 e4e5 Nf6g8 Bc1g5 d7d5 e5xd6 c7xd6
2017-02-19 09:50:36,721<--1: 9 40 4 78594 e2e4 Ng8f6 Nb1c3 Nb8c6 e4e5 Nf6g8 Bc1f4 d7d5 e5xd6 c7xd6
2017-02-19 09:50:36,768<--1:10 37 9 152475 e2e4 Ng8f6 Nb1c3 Nb8c6 e4e5 Nf6g8 Bc1f4 e7e6 Bf1c4 Bf8b4
2017-02-19 09:50:36,846<--1:11 45 17 324844 e2e4 Ng8f6 Nb1c3 Nb8c6 e4e5 Nf6g8 Bc1f4 e7e6 Ng1f3 Bf8b4 Bf1c4
2017-02-19 09:50:36,986<--1:12 28 31 584383 e2e3
2017-02-19 09:50:37,033<--1:12 37 35 658113 e2e3 d7d5 Ng1f3 Ng8f6 Bf1d3 g7g6 O-O Bf8g7 c2c4 O-O Nb1c3 Nb8c6 c4xd5 Nf6xd5
2017-02-19 09:50:37,096<--1:12 37 42 757732 e2e3 d7d5 Ng1f3 Ng8f6 Bf1d3 g7g6 O-O Bf8g7 c2c4 O-O Nb1c3 Nb8c6 c4xd5 Nf6xd5
2017-02-19 09:50:37,267<--1:13 45 59 1099172 e2e4
2017-02-19 09:50:37,470<--1:13 44 79 1485395 e2e3 d7d5 Ng1f3 Ng8f6 Bf1d3 g7g6 O-O Bf8g7 c2c4 Nb8c6 c4xd5 Qd8xd5 Nb1c3
2017-02-19 09:50:37,704<--1:14 46 103 1925066 e2e3 d7d5 Ng1f3 Ng8f6 Bf1d3 g7g6 O-O Bf8g7 c2c4 d5xc4 Bd3xc4 b7b5 Bc4d3 O-O
2017-02-19 09:50:38,734<--1:15 37 206 3888900 e2e4
2017-02-19 09:50:39,529<--1:15 57 285 5433225 e2e4 Ng8f6 e4e5 Nf6d5 Ng1f3 d7d6 Bf1d3 Nd5b4 O-O d6xe5 d4xe5 Nb4xd3 Qd1xd3 Qd8xd3 c2xd3
2017-02-19 09:50:39,592<--1:15 57 291 5570143 e2e4 Ng8f6 e4e5 Nf6d5 Ng1f3 d7d6 Bf1d3 Nd5b4 O-O d6xe5 d4xe5 Nb4xd3 Qd1xd3 Qd8xd3 c2xd3
2017-02-19 09:50:41,276<--1:16 72 460 8950372 e2e4 Nb8c6 Ng1f3 d7d5 e4xd5 Qd8xd5 Nb1c3 Qd5d8 Bc1e3 Bc8f5 Bf1d3 Bf5xd3 Qd1xd3 Nc6b4 Qd3f5 e7e6
2017-02-19 09:50:42,914<--1:17 58 624 12282306 e2e4 Ng8f6 e4e5 Nf6d5 Ng1f3 d7d6 Bf1d3 Nd5b4 O-O Nb8c6 e5xd6 c7xd6 Nb1c3 Nb4xd3 Qd1xd3 Nc6b4 Qd3e4
2017-02-19 09:50:44,396<--1:18 62 772 15386693 e2e4 Ng8f6 e4e5 Nf6d5 Ng1f3 e7e6 Bf1d3 Nd5b4 Bd3e4 d7d5 e5xd6 Bf8xd6 O-O O-O c2c3 Nb4d5 Nf3g5 g7g6
2017-02-19 09:50:47,204<--1:19 63 1053 21483085 e2e4 Ng8f6 e4e5 Nf6d5 Ng1f3 e7e6 Bf1d3 Nd5b4 O-O Nb4xd3 Qd1xd3 Bf8e7 Nb1c3 O-O Bc1f4 Nb8c6 Nc3e4 d7d5 Ne4g5
2017-02-19 09:50:51,744<--1:20 65 1507 31061294 e2e4 Ng8f6 e4e5 Nf6d5 Ng1f3 d7d6 Bf1c4 Nd5b6 Bc4b3 Nb8c6 O-O d6d5 Nb1c3 a6a5 Nc3a4 Nb6xa4 Bb3xa4 Ra8a6 Ba4b5 Ra6b6
2017-02-19 09:51:32,148<--1:21 49 5547 115678630 e2e4 e7e6 Nb1c3 d7d5 Ng1f3 Ng8f6 Bf1d3 Bf8b4 e4e5 Nf6e4 Bd3xe4 d5xe4 Nf3g5 c7c5 d4xc5 Qd8xd1+ Ke1xd1 O-O Nc3xe4 Nb8c6 a2a3 Bb4a5
2017-02-19 09:52:08,636<--1:22 49 9196 192549422 e2e4 e7e6 Nb1c3 d7d5 Ng1f3 Ng8f6 Bf1d3 Nb8c6 O-O Bf8e7 e4e5 Nf6d7 Bc1f4 O-O Qd1d2 Rf8e8 Rf1e1 Nc6b4 Bf4g5 c7c5 Bg5xe7 Re8xe7
2017-02-19 09:52:35,986<--1:23 52 11931 251334861 e2e4 e7e6 Nb1c3 d7d5 Ng1f3 Ng8f6 Bf1d3 Nb8c6 O-O Bf8e7 e4e5 Nf6d7 Bc1f4 O-O Qd1d2 Rf8e8 Rf1e1 Nc6b4 Bf4g5 Be7xg5 Nf3xg5 Nb4xd3 c2xd3 last PV shown after around 119,3 sec thinking for this move - wall clock and engine output agree on this
2017-02-19 09:55:38,195-->1:?
2017-02-19 09:55:38,210<--1:move e2e4 move appears after around 301,5 sec according to the wall clock at 09:55:38,210
2017-02-19 09:55:38,210*1*---------> Arena:Illegaler Zug!: "e2e4" ()
2017-02-19 09:58:10,033-->1:?
2017-02-19 09:58:10,033-->1:force
2017-02-19 09:58:10,033-->1:quit

[hgm]

I must be getting blind... I was convinced that it was only slightly over 2 min. I must have mixed up minutes and seconds. Sorry about this.