def nvprocess_view(self, vchange_list):
for r in vchange_list:
key = get_asymm_key(r.inner.id, ktype="sign")
if not bool(key):
return
m = message.check(key, r)
if m == None:
return False
return True
def execute(self, req):
"""
clientbuff is used to maintain buffer for failed requests for retries
"""
seq = req.inner.seq
dig = req.inner.msg
client_req, t, fd = self.active[dig]
t.cancel()
key = get_asymm_key(self.id, ktype="sign")
if not bool(key):
return
#time.sleep(1)
#rc = ecdsa_sig.verify(self.ecdsa_key, self.hello_sig, "Hello world!")
#cond.acquire()
## Horrible hack...
#self.last_executed = max(seq,self.last_executed)
m = self.bank.process_request(key, self.id, seq, client_req)
client_req.inner.msg = "TRAN00000000"
#for i in range(1024):
discard = self.bank.process_request(key, self.id, seq, client_req)
#cond.release()
#if self.id == self.primary and fd in self.fdmap:
#if fd in self.fdmap:
#self.safe_send(self.fdmap[fd], m)
#self.clean(fd)
time.sleep(0.05)
if self.max_requests and seq > self.max_requests:
return
client_sock = socks.socksocket() # socket.socket()
# import pdb; pdb.set_trace()
client_sock.setproxy(socks.PROXY_TYPE_SOCKS5, "127.0.0.1", config.TOR_SOCKSPORT[self.id], True)
ip, port = client_address
#TODO: hack
retry = True
#while retry:
self.clientlock.acquire()
try:
client_sock.connect((ip, port))
client_sock.send(self.clientbuff+serialize(m))
client_sock.close()
self.clientbuff = bytes()
except:
print("failed to send, adding to client outbuff")
self.clientbuff += serialize(m)
#continue
self.clientlock.release()
#retry = False
record(self.debuglog, "EXECUTED " + str(seq))
#print("adding request with sequence number " + str(req.inner.seq) + " to queue")
if self.max_requests and seq >= self.max_requests:
print("max requests reached, shutting down..")
#sys.exit()
t = Timer(5, self.suicide)
t.start()
def vprocess_prepare(self, vprep_dict, vpre_dict, last_checkpoint):
max = 0
#[seq][id] -> req
counter = {}
for k1,v1 in vprep_dict.iteritems():
if (not k1 in vpre_dict):
return False,0
dig = vpre_dict[k1].inner.msg
key = get_asymm_key(vpre_dict[k1].inner.id, ktype="sign")
if not bool(key):
return
r = message.check(key, vpre_dict[k1])
if r == None:
return False,0
for k2,v2 in v1.iteritems():
# check sigs
key = get_asymm_key(v2.inner.id, ktype="sign")
if not bool(key):
return
r = message.check(key,v2)
if r == None:
return False,0
#prepares need to be for the same digest
if r.inner.msg != dig:
return False
if (vpre_dict[k1].inner.id == r.inner.id):
return False,0
if r.inner.seq < last_checkpoint:
return False,0
if r.inner.seq > max:
max = r.inner.seq
if r.inner.seq not in counter:
counter[r.inner.seq] = 1
else:
counter[r.inner.seq] += 1
for k in counter:
if counter[k] < 2*self.f:
return False,0
self.add_node_history(vpre_dict[k])
return True,max
def create_request(self, req_type, seq, msg, outer_req=None):
key = get_asymm_key(self.id, ktype="sign")
if not bool(key):
return
# import pdb; pdb.set_trace()
msg = bytes(msg, encoding='utf-8')
m = message.add_sig(key, self.id, seq, self.view, req_type, msg)
if outer_req:
m.outer = outer_req.SerializeToString()
return m
def process_new_view(self, req, fd):
_logger.info("Phase - PROCESS NEW VIEW - fd [%s] - new_view from ID [%s]" % \
(fd, req.inner.id))
# parse requests by type
m = req.inner.msg
vchange_list = []
prpr_list = []
counter = 0
while len(m) > 0:
counter += 1
_logger.info("COUNTER [%s]" % counter)
b = m[:4]
size = struct.unpack("!I",b)[0]
try:
r2 = request_pb2.Request()
r2.ParseFromString(m[4:size+4])
record_pbft(self.debuglog, r2)
key = get_asymm_key(r2.inner.id, ktype="sign")
if not bool(key):
return
r2 = message.check(key, r2)
if r2 == None:
_logger.warn("FAILED SIG CHECK IN NEW VIEW")
return
except:
r2 = None
_logger.warn("FAILED PROTOBUF EXTRACT IN NEW VIEW")
return
if r2.inner.type == "VCHA":
vchange_list.append(r2)
if r2.inner.type == "PRPR":
prpr_list.append(r2)
m = m[size+4:]
if not self.nvprocess_view(vchange_list):
_logger.warn("FAILED VCHANGE VALIDATION IN NEW VIEW")
return
if req.inner.view >= self.view:
self.view = req.inner.view
self.view_active = True
self.primary = self.view % self.N
self.active = {}
self.reset_message_log()
#TODO: Move this check to execute
self.client_message_log = {}
self.prepared = {}
rc2 = self.nvprocess_prpr(prpr_list)
_logger.info("New view - %s - accepted" % self.view)
return
def gen_requests(max_requests, batch_size, f):
client_key_pub = ecdsa_sig.get_asymm_key(client_id - 1, ktype='verify')
client_key_pem = ecdsa_sig.get_asymm_key(client_id - 1, ktype='sign')
for i in range(max_requests):
# print("for request: [%s]" % i)
r = random.randint(1, max_requests)
r2 = random.randint(max_requests / 2, max_requests)
# amount = random.randint(0,100)
amount = 50
msg = "TRAN%s%s%s" % (str(amount).zfill(4), str(r2).zfill(4),
client_key_pub.to_string().decode('latin1'))
if i == 0:
print(len(client_key_pub.to_string()), "ECDSA")
current_key = ecdsa_sig.get_asymm_key(r % N, ktype='sign')
# import pdb; pdb.set_trace()
req = message.add_sig(current_key, r, 0, 0, "REQU",
msg.encode('utf-8'), i)
req.sig = client_key_pem.to_string()
msg = req.SerializeToString()
if i == 0:
print("one request:", len(msg))
################################
#padding = "0123456789" * 10
#msg += padding * 1024
#msg += "x" * (400 - len(msg))
msg += msg * (batch_size - 1)
################################
temp = message.add_sig(current_key, r, 0, 0, "REQU", msg, i + offset)
size = temp.ByteSize()
if i == 0:
print("inner message:", len(msg))
print("message byte size:", size)
b = struct.pack("!I", size)
f.write(b + temp.SerializeToString())
# if i % batch_size == 0:
s = "Generation Progress: {:.1%}".format(i / max_requests)
print(s, end='')
backspace(len(s))
def parse_request(self, request_bytes, fd):
# attempt to reconstruct the request object
# close connection and return on failure
_logger.info("Phase - PARSE REQUEST - fd [%s]" % fd)
try:
# import pdb; pdb.set_trace()
req = request_pb2.Request()
req.ParseFromString(request_bytes)
record_pbft(self.debuglog, req)
key = get_asymm_key(req.inner.id, ktype="sign")
if not bool(key):
return
# if not isinstance(key, ecdsa.SigningKey):
if not bool(key):
return
req = message.check(key, req)
if req is None:
_logger.error("Failed message sig check. 'req' is empty..")
return
except Exception as E:
req = None
_logger.error("ERROR IN PROTOBUF TYPES: %s" % E)
# raise # for debug
self.clean(fd)
return
# print(req.inner.type, len(request_bytes))
# TODO: Check for view number and view change, h/H
if req.inner.view != self.view or not self.view_active:
if req.inner.type != "VCHA" and req.inner.type != "NEVW" and \
req.inner.type != "CHKP" and req.inner.type != "REQU":
debug_msg = "TYPE: %s - ID %s - SEQ %s - VIEW - %s" % (
req.inner.type,
req.inner.id,
req.inner.seq,
req.inner.view
)
_logger.warn("Bad view number - %s" % debug_msg)
return
if self.in_node_history(req):
_logger.warn("Duplicate node message")
# return
pass
if req.inner.type in self.request_types and not self.in_client_history(req):
# call to actual success
self.request_types[req.inner.type](req, fd)
else:
self.clean(fd)
_logger.warn("BAD MESSAGE TYPE - %s - %s" % (
req.inner.type,
req.inner.id))
def process_preprepare(self, req, fd):
_logger.info("Phase - PRE-PREPARE - fd [%s]" % fd)
if req.inner.seq in self.node_message_log["PRPR"]:
return None
# the msg field for a preprepare should be the digest of the original client request
if req.outer != "":
try:
client_req = request_pb2.Request()
client_req.ParseFromString(req.outer)
record_pbft(self.debuglog, client_req)
client_key = get_asymm_key(client_req.inner.id, ktype="sign")
if not bool(client_key):
return
client_req = message.check(client_key, client_req)
if client_req == None or req.inner.msg != client_req.dig:
_logger.warn("FAILED PRPR OUTER SIGCHECK")
return
except:
_logger.error("ERROR IN PRPR OUTER PROTOBUFF")
raise
else:
client_req = None
if req.inner.msg not in self.active:
# self.active[req.inner.msg] = (client_req, Timer(self.timeout, self.handle_timeout), fd)
request_timer = Timer(self.timeout, self.handle_timeout, [req.inner.msg, req.inner.view])
request_timer.daemon = True
request_timer.start()
self.active[req.inner.msg] = (client_req, request_timer, fd)
self.add_node_history(req)
m = self.create_request("PREP", req.inner.seq, req.inner.msg)
self.add_node_history(m)
record_pbft(self.debuglog, m)
self.inc_prep_dict(req.inner.msg)
self.broadcast_to_nodes(m)
if self.check_prepared_margin(req.inner.msg, req.inner.seq):
record(self.debuglog, "PREPARED sequence number " + str(req.inner.seq))
m = self.create_request("COMM", req.inner.seq, req.inner.msg)
self.broadcast_to_nodes(m)
self.add_node_history(m)
self.inc_comm_dict(m.inner.msg)
record_pbft(self.debuglog, m)
self.prepared[req.inner.seq] = req.inner.msg
if self.check_committed_margin(m.inner.msg, m):
record(self.debuglog, "COMMITTED sequence number " + str(m.inner.seq))
record_pbft(self.commitlog, m)
self.execute_in_order(m)
def nvprocess_prpr(self, prpr_list):
for r in prpr_list:
key = get_asymm_key(r.inner.id, ktype="sign")
if not bool(key):
return
m = message.check(key, r)
if m == None:
return False
out = self.create_request("PREP", r.inner.seq, r.inner.msg)
self.broadcast_to_nodes(out)
#TODO: add view to node_message_log, don't broadcast here
#if self.id in self.node_message_log["COMM"][r.inner.seq]:
# out = self.create_request("COMM", r.inner.seq, r.inner.msg)
# self.broadcast_to_nodes(out)
return True
def process_view_change(self, req, fd):
_logger.info("Phase - PROCESS VIEW CHANGE - fd [%s]" % fd)
print("Received a view change req from " + str(req.inner.id))
print(req.inner.view)
self.add_node_history(req)
new_v = req.inner.view
if self.id != req.inner.view or new_v < self.view:
return
# (NEVW, v+1, V, O) where V is set of valid VCHA, O is set of PRPR
#TODO (NO PIGGYBACK)
# determine the latest stable checkpoint
checkpoint = 0
# [seq][id] -> req
# for each view change message
#for r in self.view_dict[new_v]:
vcheck_list = []
vpre_dict = {}
vprep_dict = {}
m = req.inner.msg
# for each chkp, prpr, prep message it contains
while len(m) > 0:
b = m[:4]
size = struct.unpack("!I", b)[0]
try:
#if True:
r2 = request_pb2.Request()
r2.ParseFromString(m[4:size+4])
record_pbft(self.debuglog, r2)
key = get_asymm_key(r2.inner.id, ktype="sign")
if not bool(key):
return
r2 = message.check(key, r2)
if r2 is None:
print("FAILED SIG CHECK IN VIEW CHANGE")
return
except Exception as E:
#else:
r2 = None
print("FAILED PROTOBUF EXTRACT IN VIEW CHANGE: %s" % E)
raise
return
if r2.inner.type == "CHKP":
vcheck_list.append(r2)
if r2.inner.type == "PREP":
if r2.inner.seq not in vprep_dict:
vprep_dict[r2.inner.seq] = {r2.inner.id: r2}
else:
vprep_dict[r2.inner.seq][r2.inner.id] = r2
if r2.inner.type == "PRPR":
vpre_dict[r2.inner.seq] = r2
m = m[size+4:]
rc1 = self.vprocess_checkpoints(vcheck_list, req.inner.seq)
rc2,max = self.vprocess_prepare(vprep_dict, vpre_dict, req.inner.seq)
# on success add to list
# on error do nothing and return
# request list, min, max
if rc1 and rc2:
if new_v not in self.view_dict:
self.view_dict[new_v] = ([req],0,0)
else:
if not self.in_view_dict(req):
self.view_dict[new_v][0].append(req)
# set min and max
if self.view_dict[new_v][1] < req.inner.seq:
#self.view_dict[new_v][1] = req.inner.seq
temp = self.view_dict[new_v]
self.view_dict[new_v] = (temp[0], req.inner.seq, temp[2])
if self.view_dict[new_v][2] < max:
#self.view_dict[new_v][2] = max
temp = self.view_dict[new_v]
self.view_dict[new_v] = (temp[0], temp[1], max)
# proccess and send the new view request
if ((not self.view_active) or new_v > self.view) and len(self.view_dict[new_v][0]) > 2*self.f:
msg = ""
for i in self.view_dict[new_v][0]:
msg += serialize(i)
for i in range(self.view_dict[new_v][1], self.view_dict[new_v][2]):
if i == 0:
continue
r = self.node_message_log["PRPR"][i][self.primary]
temp = self.create_request("PRPR", i, r.inner.msg)
msg += serialize(temp)
out = self.create_request("NEVW", 0, msg)
self.view_active = True
self.primary = self.view % self.N
self.active = {}
self.reset_message_log()
#TODO: MOve this check to execute
self.client_message_log = {}
self.prepared = {}
self.seq = self.view_dict[new_v][2]
self.broadcast_to_nodes(out)
print("Entering New view", self.view)