go movetime functionality

[Banfelix]

Hi everyone,
I'm facing an issue regarding the go movetime command. I set a certain movetime in the config file like so:

go_commands:                   # Additional options to pass to the UCI go command.
   #nodes: 1                     # Search so many nodes only.
   #depth: 5                     # Search depth ply only.
   movetime: 1000               # Integer. Search exactly movetime milliseconds.

However, i dont think the movetime command is passed during runtime ?
The info code from the lichess bot script reads:

                             INFO                                                                                                              lichess_bot.py:817
                             INFO     move: 2                                                                                            lichess_bot.py:818
                             INFO     Searching for wtime 298910 btime 298000 for game FwXIQoKE   engine_wrapper.py:723

And the commands i read from the gui are:

                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '[UCI] <<< position     engine.py:1642
                         startpos moves f2f4 d7d5 g1f3'
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '[UCI] <<< go wtime     engine.py:1642
                         298910 btime 298000 winc 0 binc 0'   

For the very first move it works, as in engine_wrapper.py there is a hardcoded time limit of 10s, which my engine recognizes (My engine played as black, hence f2f4 was already played):

                Searching for time 10 seconds for game FwXIQoKE                       engine_wrapper.py:701
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '[UCI] <<< ucinewgame'  engine.py:1642
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '    Clearing '         engine.py:1642
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '[UCI] <<< isready'     engine.py:1642
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '[UCI] <<< position     engine.py:1642
                         startpos moves f2f4'
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: '[UCI] <<< go movetime  engine.py:1642
                         10000'
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: 'Movetime found: 10000' engine.py:1642
                WARNING  <UciProtocol (pid=54000)>: Unexpected engine output: 'movetime:10000         engine.py:1642
                         start:610047031 stop:610057031 depth:64 timeset:1'                              

If movetime is set correctly does it at least also appear in the info print?
Movetime is not restricted for longer time formats right?

Cheers, Felix B

[MarkZH]

If this is still an issue, please send the config.log and lichess-bot.log files from the lichess_bot_auto_logs directory (either paste them here or make a gist and post the link here.

Also, if you want your engine to echo the commands it receives, prefix the echo with info string. For example,

info string [UCI] <<< position startpos moves f2f4 d7d5 g1f3

This will reduce the number of warning messages since the chess library that communicates with the engine won't be confused by your engine's output.

[MarkZH]

Make sure not to send the config.yml file that you run lichess-bot with, since that contains your bot's secret token.