LBRYCommunity/lbrycrd
1
0
Fork 0
Code Issues 29 Pull requests 1 Projects Releases 32 Packages Wiki Activity

test: Add elapsed time to RPC tracing

Browse source 
Add elapsed time to output of `--tracerpc`. To find out why tests are
slow.
This commit is contained in:
Wladimir J. van der Laan 2017-04-17 10:50:00 +02:00
parent 170bc2c381
commit 20187e4ad0
1 changed files with 5 additions and 2 deletions
Show stats Download patch file Download diff file Expand all files Collapse all files

7
test/functional/test_framework/authproxy.py
View file

@ -42,6 +42,7 @@ import decimal
import json import json
import logging import logging
import socket import socket
import time
try: try:
import urllib.parse as urlparse import urllib.parse as urlparse
except ImportError: except ImportError:
@ -163,6 +164,7 @@ class AuthServiceProxy(object):
return self._request('POST', self.__url.path, postdata.encode('utf-8')) return self._request('POST', self.__url.path, postdata.encode('utf-8'))
def _get_response(self): def _get_response(self):
req_start_time = time.time()
try: try:
http_response = self.__conn.getresponse() http_response = self.__conn.getresponse()
except socket.timeout as e: except socket.timeout as e:
@ -183,8 +185,9 @@ class AuthServiceProxy(object):
responsedata = http_response.read().decode('utf8') responsedata = http_response.read().decode('utf8')
response = json.loads(responsedata, parse_float=decimal.Decimal) response = json.loads(responsedata, parse_float=decimal.Decimal)
elapsed = time.time() - req_start_time
if "error" in response and response["error"] is None: if "error" in response and response["error"] is None:
log.debug("<-%s- %s"%(response["id"], json.dumps(response["result"], default=EncodeDecimal, ensure_ascii=self.ensure_ascii))) log.debug("<-%s- [%.6f] %s"%(response["id"], elapsed, json.dumps(response["result"], default=EncodeDecimal, ensure_ascii=self.ensure_ascii)))
else: else:
log.debug("<-- "+responsedata) log.debug("<-- [%.6f] %s"%(elapsed,responsedata))
return response return response