You signed in with another tab or window. Reload to refresh your session.You signed out in another tab or window. Reload to refresh your session.You switched accounts on another tab or window. Reload to refresh your session.Dismiss alert
When using caliper-min for fabric sharp YCSB testing, no matter how I change the parameters, all transactions are submitted, and I’m not sure where it’s configured wrong(The test can be carried out correctly in the basic fabric 1.4 environment). Could you give me some suggestions? Thank you. The parameters I used are as follows:
Number of transactions: 100,000
Sending rate: 1000tps
zipf_s: 1.8
modifyRatio: 1.0
recordCount: 10
The log of the orderer node is a bit strange, the read and write dependencies of the transactions are all empty, and readKeys=[ycsb], as shown below:
2021-07-25 08:17:17.237 UTC [fsblkstorage] addBlock -> INFO 667c8 Appended Block 2000 Size: 153117
2021-07-25 08:17:17.241 UTC [orderer/common/blockcutter] Ordered -> INFO 667c9 Override the current block size with env var to 50
2021-07-25 08:17:17.241 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667ca Process msg 1c0c30b6: snapshot=1999, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.241 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667cb Txn 1c0c30b6 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.241 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667cc Schedule Txn 1c0c30b6 in 74 us. (Resolve Dependency: 30 us, Test accessibility via BFS traverse 1 txns: 42 us, Update Pending: 1 us)
2021-07-25 08:17:17.242 UTC [orderer/common/blockcutter] Ordered -> INFO 667cd Override the current block size with env var to 50
2021-07-25 08:17:17.242 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667ce Process msg 53f2789e: snapshot=1999, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.242 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667cf Txn 53f2789e Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.242 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667d0 Schedule Txn 53f2789e in 94 us. (Resolve Dependency: 34 us, Test accessibility via BFS traverse 1 txns: 54 us, Update Pending: 4 us)
2021-07-25 08:17:17.245 UTC [orderer/common/blockcutter] Ordered -> INFO 667d1 Override the current block size with env var to 50
2021-07-25 08:17:17.245 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667d2 Process msg 3d90dfa4: snapshot=1999, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.245 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667d3 Txn 3d90dfa4 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.245 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667d4 Schedule Txn 3d90dfa4 in 74 us. (Resolve Dependency: 29 us, Test accessibility via BFS traverse 1 txns: 40 us, Update Pending: 4 us)
2021-07-25 08:17:17.277 UTC [orderer/common/blockcutter] Ordered -> INFO 667d5 Override the current block size with env var to 50
2021-07-25 08:17:17.277 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667d6 Process msg 8062a008: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.277 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667d7 Txn 8062a008 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.277 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667d8 Schedule Txn 8062a008 in 77 us. (Resolve Dependency: 30 us, Test accessibility via BFS traverse 1 txns: 39 us, Update Pending: 5 us)
2021-07-25 08:17:17.283 UTC [orderer/common/blockcutter] Ordered -> INFO 667d9 Override the current block size with env var to 50
2021-07-25 08:17:17.283 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667da Process msg 51b0298e: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.283 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667db Txn 51b0298e Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.283 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667dc Schedule Txn 51b0298e in 110 us. (Resolve Dependency: 44 us, Test accessibility via BFS traverse 1 txns: 58 us, Update Pending: 6 us)
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter] Ordered -> INFO 667dd Override the current block size with env var to 50
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667de Process msg 9beae1f1: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667df Txn 9beae1f1 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667e0 Schedule Txn 9beae1f1 in 85 us. (Resolve Dependency: 35 us, Test accessibility via BFS traverse 1 txns: 42 us, Update Pending: 7 us)
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter] Ordered -> INFO 667e1 Override the current block size with env var to 50
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667e2 Process msg 2c6856ea: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667e3 Txn 2c6856ea Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.284 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667e4 Schedule Txn 2c6856ea in 96 us. (Resolve Dependency: 44 us, Test accessibility via BFS traverse 1 txns: 44 us, Update Pending: 7 us)
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter] Ordered -> INFO 667e5 Override the current block size with env var to 50
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667e6 Process msg 46fb2924: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667e7 Txn 46fb2924 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667e8 Schedule Txn 46fb2924 in 90 us. (Resolve Dependency: 41 us, Test accessibility via BFS traverse 1 txns: 38 us, Update Pending: 9 us)
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter] Ordered -> INFO 667e9 Override the current block size with env var to 50
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667ea Process msg e6b1bf5b: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.288 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667eb Txn e6b1bf5b Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.289 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667ec Schedule Txn e6b1bf5b in 79 us. (Resolve Dependency: 28 us, Test accessibility via BFS traverse 1 txns: 39 us, Update Pending: 11 us)
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter] Ordered -> INFO 667ed Override the current block size with env var to 50
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667ee Process msg 9d535ceb: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667ef Txn 9d535ceb Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667f0 Schedule Txn 9d535ceb in 95 us. (Resolve Dependency: 32 us, Test accessibility via BFS traverse 1 txns: 42 us, Update Pending: 20 us)
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter] Ordered -> INFO 667f1 Override the current block size with env var to 50
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667f2 Process msg ec494e25: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667f3 Txn ec494e25 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.291 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667f4 Schedule Txn ec494e25 in 89 us. (Resolve Dependency: 26 us, Test accessibility via BFS traverse 1 txns: 50 us, Update Pending: 10 us)
2021-07-25 08:17:17.329 UTC [orderer/common/blockcutter] Ordered -> INFO 667f5 Override the current block size with env var to 50
2021-07-25 08:17:17.329 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667f6 Process msg 47ac5689: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.329 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667f7 Txn 47ac5689 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.329 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667f8 Schedule Txn 47ac5689 in 111 us. (Resolve Dependency: 44 us, Test accessibility via BFS traverse 1 txns: 43 us, Update Pending: 22 us)
2021-07-25 08:17:17.345 UTC [orderer/common/blockcutter] Ordered -> INFO 667f9 Override the current block size with env var to 50
2021-07-25 08:17:17.345 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667fa Process msg 0a78f0bc: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.345 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667fb Txn 0a78f0bc Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.345 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 667fc Schedule Txn 0a78f0bc in 103 us. (Resolve Dependency: 39 us, Test accessibility via BFS traverse 1 txns: 42 us, Update Pending: 20 us)
2021-07-25 08:17:17.346 UTC [orderer/common/blockcutter] Ordered -> INFO 667fd Override the current block size with env var to 50
2021-07-25 08:17:17.346 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 667fe Process msg 82f2f8ee: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.346 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 667ff Txn 82f2f8ee Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.346 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 66800 Schedule Txn 82f2f8ee in 107 us. (Resolve Dependency: 40 us, Test accessibility via BFS traverse 1 txns: 48 us, Update Pending: 18 us)
2021-07-25 08:17:17.388 UTC [orderer/common/blockcutter] Ordered -> INFO 66801 Override the current block size with env var to 50
2021-07-25 08:17:17.388 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 66802 Process msg 7c5f8718: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.388 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 66803 Txn 7c5f8718 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.388 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 66804 Schedule Txn 7c5f8718 in 110 us. (Resolve Dependency: 38 us, Test accessibility via BFS traverse 1 txns: 44 us, Update Pending: 25 us)
2021-07-25 08:17:17.405 UTC [orderer/common/blockcutter] Ordered -> INFO 66805 Override the current block size with env var to 50
2021-07-25 08:17:17.405 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 66806 Process msg c1f562e6: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.406 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 66807 Txn c1f562e6 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.406 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 66808 Schedule Txn c1f562e6 in 126 us. (Resolve Dependency: 42 us, Test accessibility via BFS traverse 1 txns: 53 us, Update Pending: 29 us)
2021-07-25 08:17:17.448 UTC [orderer/common/blockcutter] Ordered -> INFO 66809 Override the current block size with env var to 50
2021-07-25 08:17:17.448 UTC [orderer/common/blockcutter] scheduleMsg -> INFO 6680a Process msg 9910a836: snapshot=2000, committed=2001, readKeys=[ycsb], writeKeys=[]
2021-07-25 08:17:17.448 UTC [orderer/common/blockcutter/scheduler] ProcessTxn -> INFO 6680b Txn 9910a836 Dependency: wr=[[]], ww=[[]], rw=[[]], anti-rw=[[]], pending-anti-rw=[[]], freshly read keys=[ycsb]
2021-07-25 08:17:17.448 UTC [orderer/common/blockcutter/scheduler] Infof-fm -> INFO 6680c Schedule Txn 9910a836 in 114 us. (Resolve Dependency: 38 us, Test accessibility via BFS traverse 1 txns: 56 us, Update Pending: 18 us)
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 6680d Retrieve Schedule for block 2001 ( average spanned = 1.18 ) in 363 us.
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 6680e Compute schedule from topo sort in 32 us
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 6680f Resolve pending txns in 4 us
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 66810 Store committed keys in 65 us
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 66811 Topo Traverse 0 txns to update ww dependency in 1 us
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 66812 Remove 50 non-concerned txns from TxnPQueue in 196 us
2021-07-25 08:17:17.492 UTC [orderer/common/blockcutter/scheduler] func1 -> INFO 66813 # of Concerned txns in the queue : 417
2021-07-25 08:17:17.492 UTC [fsblkstorage] addBlock -> INFO 66814 Appended Block 2001 Size: 53292
The results are as follows:
###test result:###
+-----------------+-----------+-------+------+------------+-------------+-------------+----------------+----------------+-------------+------------+
| Name | Operation | Succ | Fail | Send Rate | Max Latency | Min Latency | 95%ile Latency | 99%ile Latency | Avg Latency | Throughput |
|-----------------|-----------|-------|------|------------|-------------|-------------|----------------|----------------|-------------|------------|
| txn-1.8-1000-10 | invoke | 99960 | 0 | 992.64 tps | 0.909 s | 0.086 s | 0.458 s | 0.551 s | 0.316 s | 991.61 tps |
|-----------------|-----------|-------|------|------------|-------------|-------------|----------------|----------------|-------------|------------|
| txn-1.8-1000-10 | overall | 99960 | 0 | 992.64 tps | 0.909 s | 0.086 s | 0.458 s | 0.551 s | 0.316 s | 991.61 tps |
+-----------------+-----------+-------+------+------------+-------------+-------------+----------------+----------------+-------------+------------+
+-----------------+-------+---------+---------+---------+-------------+
| Name | Succ | Avg S2E | Avg E2O | Avg O2F | Avg Latency |
|-----------------|-------|---------|---------|---------|-------------|
| txn-1.8-1000-10 | 99960 | 0.012 s | 0.007 s | 0.297 s | 0.316 s |
+-----------------+-------+---------+---------+---------+-------------+
ok 5 passed 'txn-1.8-1000-10' testing with 402 blocks
----------finished test----------
###all test results:###
+------+-----------------+-----------+-------+------+------------+-------------+-------------+----------------+----------------+-------------+------------+
| Test | Name | Operation | Succ | Fail | Send Rate | Max Latency | Min Latency | 95%ile Latency | 99%ile Latency | Avg Latency | Throughput |
|------|-----------------|-----------|-------|------|------------|-------------|-------------|----------------|----------------|-------------|------------|
| 1 | txn-1.8-1000-10 | overall | 99960 | 0 | 992.64 tps | 0.909 s | 0.086 s | 0.458 s | 0.551 s | 0.316 s | 991.61 tps |
+------+-----------------+-----------+-------+------+------------+-------------+-------------+----------------+----------------+-------------+------------+
[Transaction Info] - Submitted: 99960 Succ: 99960 Fail:0 Unfinished:0 ( submited: 4 commited: 48 succ: 48 failed: 0)
sleep 5
1..5
# tests 5
# pass 5
# ok
Hi, Thank you for your reach out. As you can see, it is because FabricSharp can not capture the read-write records of transactions. I suspect it is because read-write records in your case do not reside in the 0th namespace of transactions, which is as coded here. You may try to change the above setting, or unmarshal the transaction here for a deep look.
When using caliper-min for fabric sharp YCSB testing, no matter how I change the parameters, all transactions are submitted, and I’m not sure where it’s configured wrong(The test can be carried out correctly in the basic fabric 1.4 environment). Could you give me some suggestions? Thank you. The parameters I used are as follows:
Number of transactions: 100,000
Sending rate: 1000tps
zipf_s: 1.8
modifyRatio: 1.0
recordCount: 10
The log of the orderer node is a bit strange, the read and write dependencies of the transactions are all empty, and readKeys=[ycsb], as shown below:
The results are as follows:
caliper-min benchmark configuration file:
caliper-min network configuration file:
I created a solo consensus 4peer1orderer network. the docker-compose files for peer and orderer are as follow:
peer:
orderer:
the docker file version:
The text was updated successfully, but these errors were encountered: