resetting dropped connection

[jdart1]

In xboard mode, I am seeing "resetting dropped connection" messages in the debug log, looks like after each move. Each such reset seems to consume about 0.5 second.

2022-03-27 15:44:53,549: �[32m  Starting new HTTPS connection (1): lichess.org:443�[0m
2022-03-27 15:44:54,137: �[32m  https://lichess.org:443 "GET /api/account HTTP/1.1" 200 None�[0m
2022-03-27 15:44:54,138: �[94m  Welcome ArasanX!�[0m
2022-03-27 15:44:54,138: �[94m  You're now connected to https://lichess.org/ and awaiting challenges.�[0m
2022-03-27 15:44:54,162: �[32m  Starting new HTTPS connection (1): lichess.org:443�[0m
2022-03-27 15:44:54,324: �[32m  https://lichess.org:443 "GET /api/account/playing HTTP/1.1" 200 17�[0m
2022-03-27 15:44:54,663: �[32m  https://lichess.org:443 "GET /api/stream/event HTTP/1.1" 200 None�[0m
2022-03-27 15:48:04,807: �[32m  Event: {'type': 'challenge', 'challenge': {'id': 'Su7GOlMs', 'url': 'https://lichess.org/Su7GOlMs', 'status': 'created', 'challenger': {'id': 'arasanx', 'name': 'ArasanX', 'title': 'BOT', 'rating': 2000, 'provisional': True, 'online': True}, 'destUser': {'id': 'fornax-engine', 'name': 'fornax-engine', 'title': 'BOT', 'rating': 2138, 'online': True}, 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'rated': False, 'speed': 'blitz', 'timeControl': {'type': 'clock', 'limit': 300, 'increment': 1, 'show': '5+1'}, 'color': 'random', 'finalColor': 'white', 'perf': {'icon': '\ue01d', 'name': 'Blitz'}}, 'compat': {'bot': True, 'board': True}}�[0m
2022-03-27 15:48:04,809: �[32m  Resetting dropped connection: lichess.org�[0m
2022-03-27 15:48:05,297: �[32m  https://lichess.org:443 "POST /api/challenge/Su7GOlMs/decline HTTP/1.1" 404 None�[0m
2022-03-27 15:48:05,297: �[32m  Giving up api_post(...) after 1 tries (requests.exceptions.HTTPError: 404 Client Error: Not Found for url: https://lichess.org/api/challenge/Su7GOlMs/decline)�[0m
2022-03-27 15:48:05,298: �[32m  Event: {'type': 'gameStart', 'game': {'fullId': 'Su7GOlMsPejX', 'gameId': 'Su7GOlMs', 'fen': 'rnbqkbnr/pppppppp/8/8/8/8/PPPPPPPP/RNBQKBNR w KQkq - 0 1', 'color': 'white', 'lastMove': '', 'source': 'friend', 'variant': {'key': 'standard', 'name': 'Standard'}, 'speed': 'blitz', 'perf': 'blitz', 'rated': False, 'hasMoved': False, 'opponent': {'id': 'fornax-engine', 'username': 'BOT fornax-engine', 'rating': 2138}, 'isMyTurn': True, 'secondsLeft': 300, 'compat': {'bot': True, 'board': True}, 'id': 'Su7GOlMs'}}�[0m
2022-03-27 15:48:05,298: �[94m  --- Process Used. Total Queued: 0. Total Used: 1�[0m
2022-03-27 15:48:05,306: �[32m  Starting new HTTPS connection (1): lichess.org:443�[0m
2022-03-27 15:48:05,812: �[32m  https://lichess.org:443 "GET /api/bot/game/stream/Su7GOlMs HTTP/1.1" 200 None�[0m
2022-03-27 15:48:05,813: �[32m  Initial state: {'id': 'Su7GOlMs', 'variant': {'key': 'standard', 'name': 'Standard', 'short': 'Std'}, 'clock': {'initial': 300000, 'increment': 1000}, 'speed': 'blitz', 'perf': {'name': 'Blitz'}, 'rated': False, 'createdAt': 1648396090068, 'white': {'id': 'arasanx', 'name': 'ArasanX', 'title': 'BOT', 'rating': 2000, 'provisional': True}, 'black': {'id': 'fornax-engine', 'name': 'fornax-engine', 'title': 'BOT', 'rating': 2138}, 'initialFen': 'startpos', 'type': 'gameFull', 'state': {'type': 'gameState', 'moves': '', 'wtime': 300000, 'btime': 300000, 'winc': 1000, 'binc': 1000, 'status': 'started'}}�[0m
2022-03-27 15:48:05,814: �[32m  Using selector: EpollSelector�[0m
2022-03-27 15:48:05,815: �[32m  Using PollingChildWatcher�[0m
2022-03-27 15:48:05,818: �[32m  <XBoardProtocol (pid=101766)>: Connection made�[0m
2022-03-27 15:48:05,819: �[32m  <XBoardProtocol (pid=101766)>: << xboard�[0m
2022-03-27 15:48:05,819: �[32m  <XBoardProtocol (pid=101766)>: << protover 2�[0m
2022-03-27 15:48:05,826: �[32m  <XBoardProtocol (pid=101766)>: >> Arasan v23.3.0-3-g694b944 Copyright 1994-2022 by Jon Dart. All Rights Reserved.�[0m
2022-03-27 15:48:05,903: �[32m  <XBoardProtocol (pid=101766)>: >> feature name=1 setboard=1 san=1 usermove=1 ping=1 ics=1 playother=0 sigint=0 colors=0 analyze=1 debug=1 memory=1 smp=1 variants="normal" egt="syzygy" option="Favor frequent book moves -spin 50 1 100" option="Favor best book moves -spin 50 1 100" option="Favor high-weighted book moves -spin 100 1 100" option="Randomize book moves -spin 50 1 100" option="Can resign -check 1" option="Resign threshold -spin -700 -1000 0" option="Position learning -check 1" option="Strength -spin 100 0 100" option="Use NNUE -check 1" option="NNUE file -string arasan-d10-20220226.nnue" option="Move overhead -spin 30 0 1000" myname="Arasan v23.3.0-3-g694b944"�[0m
2022-03-27 15:48:05,904: �[32m  <XBoardProtocol (pid=101766)>: >> feature done=0�[0m
2022-03-27 15:48:05,916: �[32m  <XBoardProtocol (pid=101766)>: >> # found 6-man Syzygy tablebases in directory /home/jdart/chess/syzygy:/home/jdart/chess/syzygy-6man/wdl:/home/jdart/chess/syzygy-6man/dtz�[0m
2022-03-27 15:48:06,143: �[32m  <XBoardProtocol (pid=101766)>: >> # loaded network from file arasan-d10-20220226.nnue�[0m
2022-03-27 15:48:06,143: �[32m  <XBoardProtocol (pid=101766)>: >> feature done=1�[0m
2022-03-27 15:48:06,143: �[33m  <XBoardProtocol (pid=101766)>: Rejecting feature san=1�[0m
2022-03-27 15:48:06,143: �[32m  <XBoardProtocol (pid=101766)>: << rejected san�[0m
2022-03-27 15:48:06,143: �[32m  <XBoardProtocol (pid=101766)>: << accepted memory�[0m
2022-03-27 15:48:06,143: �[32m  <XBoardProtocol (pid=101766)>: << accepted smp�[0m
2022-03-27 15:48:06,143: �[32m  <XBoardProtocol (pid=101766)>: << accepted egt�[0m
2022-03-27 15:48:06,144: �[94m  {'syzygy': '/home/jdart/chess/syzygy:/home/jdart/chess/syzygy-6man/wdl:/home/jdart/chess/syzygy-6man/dtz'}�[0m
2022-03-27 15:48:06,144: �[94m  {'name': 1, 'setboard': 1, 'san': 1, 'usermove': 1, 'ping': 1, 'ics': 1, 'playother': 0, 'sigint': 0, 'colors': 0, 'analyze': 1, 'debug': 1, 'memory': 1, 'smp': 1, 'variants': 'normal', 'egt': 'syzygy', 'myname': 'Arasan v23.3.0-3-g694b944', 'done': 1}�[0m
2022-03-27 15:48:06,144: �[32m  <XBoardProtocol (pid=101766)>: << cores 32�[0m
2022-03-27 15:48:06,144: �[32m  <XBoardProtocol (pid=101766)>: << memory 32000�[0m
2022-03-27 15:48:06,144: �[32m  <XBoardProtocol (pid=101766)>: << egtpath syzygy /home/jdart/chess/syzygy:/home/jdart/chess/syzygy-6man/wdl:/home/jdart/chess/syzygy-6man/dtz�[0m
2022-03-27 15:48:06,145: �[32m  <XBoardProtocol (pid=101766)>: << name BOT fornax-engine�[0m
2022-03-27 15:48:06,145: �[32m  <XBoardProtocol (pid=101766)>: << rating 2000 2138�[0m
2022-03-27 15:48:06,145: �[32m  <XBoardProtocol (pid=101766)>: << computer�[0m
2022-03-27 15:48:06,145: �[94m  +++ https://lichess.org/Su7GOlMs/white Blitz vs BOT fornax-engine(2138)�[0m
2022-03-27 15:48:06,145: �[32m  Game state: {'type': 'gameState', 'moves': '', 'wtime': 300000, 'btime': 300000, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:06,146: �[32m  Starting new HTTPS connection (1): lichess.org:443�[0m
2022-03-27 15:48:06,635: �[32m  https://lichess.org:443 "POST /api/bot/game/Su7GOlMs/chat HTTP/1.1" 200 11�[0m
2022-03-27 15:48:06,636: �[94m  �[0m
2022-03-27 15:48:06,636: �[94m  move: 1�[0m
2022-03-27 15:48:06,636: �[94m  Searching for time 10000�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << memory 32000�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << cores 32�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << new�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << force�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << st 10�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << post�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << easy�[0m
2022-03-27 15:48:06,637: �[32m  <XBoardProtocol (pid=101766)>: << go�[0m
2022-03-27 15:48:23,127: �[32m  <XBoardProtocol (pid=101766)>: >> move d2d4�[0m
2022-03-27 15:48:23,127: �[32m  <XBoardProtocol (pid=101766)>: << ping 42848�[0m
2022-03-27 15:48:23,128: �[32m  <XBoardProtocol (pid=101766)>: >> pong 42848�[0m
2022-03-27 15:48:23,128: �[94m  ponderpv: �[0m
2022-03-27 15:48:23,129: �[32m  Resetting dropped connection: lichess.org�[0m
2022-03-27 15:48:23,624: �[32m  https://lichess.org:443 "POST /api/bot/game/Su7GOlMs/move/d2d4?offeringDraw=false HTTP/1.1" 200 11�[0m
2022-03-27 15:48:23,624: �[32m  Game state: {'type': 'chatLine', 'room': 'player', 'username': 'ArasanX', 'text': "Hi! I'm ArasanX. Good luck! Type !help for a list of commands I can respond to."}�[0m
2022-03-27 15:48:23,624: �[94m  *** https://lichess.org/Su7GOlMs/white [player] ArasanX: b"Hi! I'm ArasanX. Good luck! Type !help for a list of commands I can respond to."�[0m
2022-03-27 15:48:23,625: �[32m  Game state: None�[0m
2022-03-27 15:48:23,625: �[32m  Game state: None�[0m
2022-03-27 15:48:23,629: �[32m  Game state: {'type': 'gameState', 'moves': 'd2d4', 'wtime': 300000, 'btime': 300000, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:23,687: �[32m  Game state: {'type': 'gameState', 'moves': 'd2d4 g8f6', 'wtime': 300000, 'btime': 300000, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:23,687: �[94m  �[0m
2022-03-27 15:48:23,687: �[94m  move: 2�[0m
2022-03-27 15:48:23,687: �[94m  Searching for wtime 298000 btime 300000�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << memory 32000�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << cores 32�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << force�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << usermove g8f6�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << level 0 4:58 1.0�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << time 29800�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << otim 30000�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << post�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << hard�[0m
2022-03-27 15:48:23,688: �[32m  <XBoardProtocol (pid=101766)>: << go�[0m
2022-03-27 15:48:30,547: �[32m  <XBoardProtocol (pid=101766)>: >> move c2c4�[0m
2022-03-27 15:48:30,547: �[32m  <XBoardProtocol (pid=101766)>: << ping 16736�[0m
2022-03-27 15:48:30,547: �[32m  <XBoardProtocol (pid=101766)>: >> pong 16736�[0m
2022-03-27 15:48:30,548: �[94m  ponderpv: �[0m
2022-03-27 15:48:30,741: �[32m  https://lichess.org:443 "POST /api/bot/game/Su7GOlMs/move/c2c4?offeringDraw=false HTTP/1.1" 200 11�[0m
2022-03-27 15:48:30,742: �[32m  Game state: None�[0m
2022-03-27 15:48:30,742: �[32m  Game state: None�[0m
2022-03-27 15:48:30,746: �[32m  Game state: {'type': 'gameState', 'moves': 'd2d4 g8f6 c2c4', 'wtime': 293940, 'btime': 300000, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:35,930: �[32m  Game state: None�[0m
2022-03-27 15:48:39,981: �[32m  Game state: {'type': 'gameState', 'moves': 'd2d4 g8f6 c2c4 e7e6', 'wtime': 293940, 'btime': 291760, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:39,982: �[94m  �[0m
2022-03-27 15:48:39,982: �[94m  move: 3�[0m
2022-03-27 15:48:39,982: �[94m  Searching for wtime 291940 btime 291760�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: << easy�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: << ping 16737�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: >> pong 16737�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: << memory 32000�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: << cores 32�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: << force�[0m
2022-03-27 15:48:39,983: �[32m  <XBoardProtocol (pid=101766)>: << usermove e7e6�[0m
2022-03-27 15:48:39,984: �[32m  <XBoardProtocol (pid=101766)>: << level 0 4:51 1.0�[0m
2022-03-27 15:48:39,984: �[32m  <XBoardProtocol (pid=101766)>: << time 29194�[0m
2022-03-27 15:48:39,984: �[32m  <XBoardProtocol (pid=101766)>: << otim 29176�[0m
2022-03-27 15:48:39,984: �[32m  <XBoardProtocol (pid=101766)>: << post�[0m
2022-03-27 15:48:39,984: �[32m  <XBoardProtocol (pid=101766)>: << hard�[0m
2022-03-27 15:48:39,984: �[32m  <XBoardProtocol (pid=101766)>: << go�[0m
2022-03-27 15:48:46,833: �[32m  <XBoardProtocol (pid=101766)>: >> move g1f3�[0m
2022-03-27 15:48:46,833: �[32m  <XBoardProtocol (pid=101766)>: << ping 19520�[0m
2022-03-27 15:48:46,833: �[32m  <XBoardProtocol (pid=101766)>: >> pong 19520�[0m
2022-03-27 15:48:46,834: �[94m  ponderpv: �[0m
2022-03-27 15:48:46,835: �[32m  Resetting dropped connection: lichess.org�[0m
2022-03-27 15:48:47,325: �[32m  https://lichess.org:443 "POST /api/bot/game/Su7GOlMs/move/g1f3?offeringDraw=false HTTP/1.1" 200 11�[0m
2022-03-27 15:48:47,325: �[32m  Game state: None�[0m
2022-03-27 15:48:47,330: �[32m  Game state: {'type': 'gameState', 'moves': 'd2d4 g8f6 c2c4 e7e6 g1f3', 'wtime': 287590, 'btime': 291760, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:47,950: �[32m  Game state: None�[0m
2022-03-27 15:48:53,962: �[32m  Game state: None�[0m
2022-03-27 15:48:56,218: �[32m  Game state: {'type': 'gameState', 'moves': 'd2d4 g8f6 c2c4 e7e6 g1f3 d7d5', 'wtime': 287590, 'btime': 283870, 'winc': 1000, 'binc': 1000, 'status': 'started'}�[0m
2022-03-27 15:48:56,219: �[94m  �[0m
2022-03-27 15:48:56,219: �[94m  move: 4�[0m
2022-03-27 15:48:56,219: �[94m  Searching for wtime 285590 btime 283870�[0m

[MarkZH]

Can you post the log for a whole game (or several games)? I want to get a sense of how often this is happening.