truechain / truechain-consensus-core

TrueChain Consensus Protocol: Minerva
Apache License 2.0
161 stars 70 forks source link

after >10 iterations of pbft-client requests to nodes, the server stops dumping tx req details #54

Open arcolife opened 6 years ago

arcolife commented 6 years ago

Normal case

The last log line from server side (./bin/linux/truechain-engine) is the one containing New Sequence Item

[?]Transacion count is 1
[.]Adding transaction request 49 to block 14

[?]Received new transacion request 49 from client on node 1
fetching file:  sign3.pub
[ ]Txn verified
[?]Added request 49 to transaction pool on node 1.
[!][0] Leader acked: {{3 0 0 4  Header:<Number:15 GasLimit:5000 GasUsed:445 Timestamp:1532292697 ParentHash:"*\004I\301\027\242\227\232\256f\255A\315^\377\240w\367\371WL\233\236\0029\260\231\200\257F\017\233" TxnsHash:"\355\017c]\177\214\362ig\213\267\265k \302\027\251\203s{\312,#kA\336\307\r\324\252p\213" > Txns:<data:<AccountNonce:40 Price:40 Hash:"An'\200\2130=\007\200W\2639F\222\351\316\323\032!\216\376n\001H9e!%\253c\324J" > >  1532292697} fa34666614cacfa0e20899b695ecbdf42b8368c3b04eca7d0ac18a16d5772ea38fa0aa87f89e51634cfb021df6ada67039ffbd3a94ed885e8af531d9f99dc925 {114228187525300136869126589949946702411048996284305879532910232289786292870326 80221517476341951832338308655862238779378846682929138267853255451220187384345}}.

[.]digest 3fe70fad55c21dbacf402e8e063ae15b6f1c44944f1313da05b64de62fbfc021d0110f5f12df170621575e227e6d93fc561f361cd3fc766e6c3b912a3ed165f5.

[?]Received new transacion request 49 from client on node 2
[!][2] ProxyProcessPrePrepare {{{0 15 0 0 fa34666614cacfa0e20899b695ecbdf42b8368c3b04eca7d0ac18a16d5772ea38fa0aa87f89e51634cfb021df6ada67039ffbd3a94ed885e8af531d9f99dc925 Header:<Number:15 GasLimit:5000 GasUsed:445 Timestamp:1532292697 ParentHash:"*\004I\301\027\242\227\232\256f\255A\315^\377\240w\367\371WL\233\236\0029\260\231\200\257F\017\233" TxnsHash:"\355\017c]\177\214\362ig\213\267\265k \302\027\251\203s{\312,#kA\336\307\r\324\252p\213" > Txns:<data:<AccountNonce:40 Price:40 quest 43" Signature:"z\231\261E\262\0212t\213\376\307\3340\224|G;p\212,\314\262\203\332\202o\375\003i\211\366\252su\215 '\177\"\337o,*A]\277l*k4\360e\370\023\262\224Ua\221\312\336\246f\243\000" 
...
[.]digest f6a1aa277415fee887f573aa32f63d56b7edbcc0cb27402d53592fdb47831e42945442b2e1c48ad0e380a0f3b6b3281073f45801128566c473cb222968016be0.

fetching file:  sign3.pub
[.]digest 749e5e025e256a79c6f66c7d9d73a8e33b0fc215e6f3bdb919ebdf467f227603877719b63287677e1f5e316d1738b03eb4e23cba7337ad8a4b773a4368e4b4fc.

[ ]Txn verified
[?]Added request 49 to transaction pool on node 2.
[!][1] Broadcasting to Node.ProxyProcessPrepare, {{{1 15 0 1 fa34666614cacfa0e20899b695ecbdf42b8368c3b04eca7d0ac18a16d5772ea38fa0aa87f89e51634cfb021df6ada67039ffbd3a94ed885e8af531d9f99dc925 Header:<Number:15 GasLimit:5000 GasUsed:445 Timestamp:1532292697 ParentHash:"*\004I\301\027\242\227\232\256f\255A\315^\377\240w\367\371WL\233\236\0029\260\231\200\257F\017\233" TxnsHash:"\355\017c]\177\214\362ig\213\267\265k \302\027\251\203s{\312,#kA\336\307\r\324\252p\213" > Txns:<data:<AccountNonce:40 Price:40 Payload:"Request 40" Signature:"x\275)\322X\265\014\030\373\307\030/\307-\250\206\362e!%\253c\324J" > >  -1} 749e5e025e256a79c6f66c7d9d73a8e33b0fc215e6f3bdb919ebdf467f227603877719b63287677e1f5e316c {79268607248763335051141996654494652372639595856080284853135830670826484330562 29523290159598255139086912673083037644138473982758858904072562300196137560401}} 1}
...
...

[!][1] Committed 15

[!][0] ProxyProcessCommit 15

[!][1] ProxyProcessCommit 15

[.][0.0.0.0:40542] [2] New Sequence Item: {15 {{1 15 0 2 fa34666614cacfa0e20899b695ecbdf42b8368c3b04eca7d0ac18a16d5772ea38fa0aa87f89e51634cfb021df6ada67039ffbd3a94ed885e8af531d9f99dc925 Header:<Number:15 GasLimit:5000 GasUsed:445 Timestamp:1532292697 652bfaa50785070a9bf7c76320828871e97472ee945eea6dcae00490 {18704172220060137031230359153475805489348593663570609262442406299954186161642 114691425572839107254689997849255936013092033288100089168574433829570816936729}}}

<output ends here>

after 11 iterations of ./bin/linux/pbft-client

last line doesn't have [.][0.0.0.0:40542] [2] New Sequence Item: and stops dumping debugging info abruptly

[?]Added request 48 to transaction pool on node 2.
[?]Received new transacion request 49 from client on node 0
fetching file:  sign3.pub
[ ]Txn verified
[?]Added request 49 to transaction pool on node 0.
[?]Received new transacion request 49 from client on node 1
fetching file:  sign3.pub
[ ]Txn verified
[?]Added request 49 to transaction pool on node 1.
[?]Received new transacion request 49 from client on node 2
fetching file:  sign3.pub
[ ]Txn verified
[?]Added request 49 to transaction pool on node 2.

<abrupt stop after last log line containing [?] >

issue with channel/buffers?

samikshan commented 6 years ago

Yes. I need to check to confirm for certain what's causing this. Great find and thanks for the testing.