____ _____ _ ____ _____ / ___| |_ _| / \ | _ \ |_ _| |___ \ | | / _ \ | |_) | | | ___) | | | / ___ \ | _ < | | |____/ |_| /_/ \_\ |_| \_\ |_| Caliper Benchmarks Test ................. 2021.11.01-11:05:54.685 info  [caliper] [cli-launch-master] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:54.686 info  [caliper] [cli-launch-master] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:54.686 info  [caliper] [cli-launch-master] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:54.686 info  [caliper] [cli-launch-master] Set SUT type: fabric 2021.11.01-11:05:54.689 info  [caliper] [benchmark-validator] No observer specified, will default to `none` 2021.11.01-11:05:54.689 info  [caliper] [caliper-engine] Starting benchmark flow 2021.11.01-11:05:55.127 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:05:55.473 info  [caliper] [caliper-engine] Network configuration attribute "caliper.command.start" is not present, skipping start command 2021.11.01-11:05:55.473 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:05:55.733 warn  [caliper] [adapters/fabric] Org1's registrar's materials found locally in file system key-value stores. Make sure it is the right one! 2021.11.01-11:05:55.740 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:05:55.748 info  [caliper] [adapters/fabric] client0.org1.example.com's materials are successfully loaded 2021.11.01-11:05:55.748 info  [caliper] [adapters/fabric] Channel 'energytradingchannel' is configured as created, skipping creation 2021.11.01-11:05:55.780 info  [caliper] [adapters/fabric] peer0.org1.example.com has already joined energytradingchannel 2021.11.01-11:05:55.790 info  [caliper] [adapters/fabric] peer1.org1.example.com has already joined energytradingchannel 2021.11.01-11:05:55.801 info  [caliper] [adapters/fabric] peer2.org1.example.com has already joined energytradingchannel 2021.11.01-11:05:55.801 info  [caliper] [caliper-engine] Executed "init" step in 0.328 seconds 2021.11.01-11:05:55.802 info  [caliper] [adapters/fabric] Installing chaincodes for energytradingchannel... 2021.11.01-11:05:55.824 info  [caliper] [adapters/fabric] OneOneEnergyTradingv27@v0 is already installed on peer0.org1.example.com 2021.11.01-11:05:55.824 info  [caliper] [adapters/fabric] Instantiating OneOneEnergyTradingv27@v0 in energytradingchannel. This might take some time... 2021.11.01-11:05:55.846 info  [caliper] [adapters/fabric] OneOneEnergyTradingv27@v0 is already instantiated in energytradingchannel 2021.11.01-11:05:55.846 info  [caliper] [caliper-engine] Executed "install" step in 0.045 seconds 2021.11.01-11:05:55.846 info  [caliper] [messenger.js] Creating messenger of type "process-master" 2021.11.01-11:05:55.849 info  [caliper] [null-observer] Configured "null" observer 2021.11.01-11:05:57.888 info  [caliper] [round-orchestrator] Monitors successfully started 2021.11.01-11:05:57.888 info  [caliper] [round-orchestrator] Started round 1 (test STARTMATCHING fixed-rate for SELLER round 1) 2021.11.01-11:05:57.890 info  [caliper] [worker-orchestrator] Launching worker 1 of 16 2021.11.01-11:05:57.901 info  [caliper] [worker-orchestrator] Launching worker 2 of 16 2021.11.01-11:05:57.908 info  [caliper] [worker-orchestrator] Launching worker 3 of 16 2021.11.01-11:05:57.915 info  [caliper] [worker-orchestrator] Launching worker 4 of 16 2021.11.01-11:05:57.921 info  [caliper] [worker-orchestrator] Launching worker 5 of 16 2021.11.01-11:05:57.928 info  [caliper] [worker-orchestrator] Launching worker 6 of 16 2021.11.01-11:05:57.936 info  [caliper] [worker-orchestrator] Launching worker 7 of 16 2021.11.01-11:05:57.944 info  [caliper] [worker-orchestrator] Launching worker 8 of 16 2021.11.01-11:05:57.951 info  [caliper] [worker-orchestrator] Launching worker 9 of 16 2021.11.01-11:05:57.957 info  [caliper] [worker-orchestrator] Launching worker 10 of 16 2021.11.01-11:05:57.965 info  [caliper] [worker-orchestrator] Launching worker 11 of 16 2021.11.01-11:05:57.972 info  [caliper] [worker-orchestrator] Launching worker 12 of 16 2021.11.01-11:05:57.984 info  [caliper] [worker-orchestrator] Launching worker 13 of 16 2021.11.01-11:05:57.992 info  [caliper] [worker-orchestrator] Launching worker 14 of 16 2021.11.01-11:05:58.003 info  [caliper] [worker-orchestrator] Launching worker 15 of 16 2021.11.01-11:05:58.012 info  [caliper] [worker-orchestrator] Launching worker 16 of 16 2021.11.01-11:05:58.023 info  [caliper] [worker-orchestrator] Messenger not configured, entering configure phase... 2021.11.01-11:05:58.024 info  [caliper] [worker-orchestrator] No existing workers detected, entering worker launch phase... 2021.11.01-11:05:58.024 info  [caliper] [worker-orchestrator] Waiting for 16 workers to be connected... 2021.11.01-11:05:58.311 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.312 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.312 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.312 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.315 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.342 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.343 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.344 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.344 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.346 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.356 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.357 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.357 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.357 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.359 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.364 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.365 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.366 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.366 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.368 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.388 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.389 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.389 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.390 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.392 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.391 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.393 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.393 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.393 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.397 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.402 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.403 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.404 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.404 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.404 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.405 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.405 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.405 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.406 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.408 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.413 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.415 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.415 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.415 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.415 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.416 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.416 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.416 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.418 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.419 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.459 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.460 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.460 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.460 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.463 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.466 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.467 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.467 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.467 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.469 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.474 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.475 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.475 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.475 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.477 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.530 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.531 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.531 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.532 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.534 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.543 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.544 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.544 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.544 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.547 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:05:58.562 info  [caliper] [cli-launch-worker] Set workspace path: /home/dhkim/energy-trading-project/caliper-benchmarks 2021.11.01-11:05:58.563 info  [caliper] [cli-launch-worker] Set benchmark configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/benchmarks/scenario/oneone_energy_trading/oneone_config_modify_peers_orderers.yaml 2021.11.01-11:05:58.563 info  [caliper] [cli-launch-worker] Set network configuration path: /home/dhkim/energy-trading-project/caliper-benchmarks/networks/fabric/fabric-v1.4.3_energy_trading/caliper_config.yaml 2021.11.01-11:05:58.563 info  [caliper] [cli-launch-worker] Set SUT type: fabric 2021.11.01-11:05:58.565 info  [caliper] [messenger.js] Creating messenger of type "process-worker" for SUT fabric 2021.11.01-11:06:03.045 info  [caliper] [worker-orchestrator] 16 workers connected, progressing to worker assignment phase. 2021.11.01-11:06:03.045 info  [caliper] [worker-orchestrator] Workers currently unassigned, awaiting index assignment... 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.074 info  [caliper] [message-handler] Handling "init" message 2021.11.01-11:06:03.078 info  [caliper] [worker-orchestrator] Waiting for 16 workers to be assigned... 2021.11.01-11:06:03.087 info  [caliper] [worker-orchestrator] 16 workers assigned, progressing to worker initialization phase. 2021.11.01-11:06:03.087 info  [caliper] [worker-orchestrator] Waiting for 16 workers to be ready... 2021.11.01-11:06:03.613 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.625 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.628 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.630 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.691 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.696 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.702 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.708 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.721 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.722 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.736 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.741 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.778 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.809 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.820 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:03.846 info  [caliper] [adapters/fabric] Initializing standard adaptor compatible with installed SDK: 1.4.7 2021.11.01-11:06:04.061 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.087 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.094 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.142 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.173 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.181 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.207 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.221 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.263 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.271 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.279 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.312 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.315 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.325 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.366 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.415 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.428 info  [caliper] [message-handler] Handled successful "init" message for worker 10 2021.11.01-11:06:04.445 info  [caliper] [adapters/fabric] Fabric SDK version: 1.4.7; TLS: server 2021.11.01-11:06:04.470 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.479 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.481 info  [caliper] [message-handler] Handled successful "init" message for worker 13 2021.11.01-11:06:04.493 info  [caliper] [message-handler] Handled successful "init" message for worker 3 2021.11.01-11:06:04.500 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.513 info  [caliper] [message-handler] Handled successful "init" message for worker 5 2021.11.01-11:06:04.600 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.607 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.609 info  [caliper] [message-handler] Handled successful "init" message for worker 14 2021.11.01-11:06:04.614 info  [caliper] [message-handler] Handled successful "init" message for worker 8 2021.11.01-11:06:04.618 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.626 info  [caliper] [message-handler] Handled successful "init" message for worker 12 2021.11.01-11:06:04.640 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.652 info  [caliper] [message-handler] Handled successful "init" message for worker 11 2021.11.01-11:06:04.658 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.665 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.667 info  [caliper] [message-handler] Handled successful "init" message for worker 1 2021.11.01-11:06:04.670 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.679 info  [caliper] [message-handler] Handled successful "init" message for worker 9 2021.11.01-11:06:04.679 info  [caliper] [message-handler] Handled successful "init" message for worker 0 2021.11.01-11:06:04.687 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.694 info  [caliper] [message-handler] Handled successful "init" message for worker 2 2021.11.01-11:06:04.754 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.761 info  [caliper] [message-handler] Handled successful "init" message for worker 15 2021.11.01-11:06:04.764 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.771 info  [caliper] [message-handler] Handled successful "init" message for worker 6 2021.11.01-11:06:04.773 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.781 info  [caliper] [message-handler] Handled successful "init" message for worker 7 2021.11.01-11:06:04.807 info  [caliper] [adapters/fabric] Org1's admin's materials are successfully loaded 2021.11.01-11:06:04.814 info  [caliper] [message-handler] Handled successful "init" message for worker 4 2021.11.01-11:06:04.815 info  [caliper] [worker-orchestrator] 16 workers ready, progressing to test preparation phase. 2021.11.01-11:06:04.817 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.822 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.826 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.830 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.832 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.834 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.835 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.835 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.836 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.836 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.837 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.837 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.837 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.838 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.839 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.840 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:04.925 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.926 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.929 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.934 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.934 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.937 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.942 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.945 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.947 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.949 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.958 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.959 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.962 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.963 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.967 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:06:04.976 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:06:09.825 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.828 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.835 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.837 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.838 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.839 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.842 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.843 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.843 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.844 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.844 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.844 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.846 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.847 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.847 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.847 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:09.925 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:06:09.926 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 0 2021.11.01-11:06:09.927 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:06:09.928 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 0 2021.11.01-11:06:09.930 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:06:09.931 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 0 2021.11.01-11:06:09.935 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:06:09.935 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:06:09.936 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 0 2021.11.01-11:06:09.936 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 0 2021.11.01-11:06:09.939 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:06:09.940 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 0 2021.11.01-11:06:09.944 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:06:09.945 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 0 2021.11.01-11:06:09.946 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:06:09.947 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 0 2021.11.01-11:06:09.948 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:06:09.949 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 0 2021.11.01-11:06:09.950 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:06:09.951 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 0 2021.11.01-11:06:09.959 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:06:09.960 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 0 2021.11.01-11:06:09.960 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:06:09.961 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 0 2021.11.01-11:06:09.963 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:06:09.964 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 0 2021.11.01-11:06:09.965 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:06:09.966 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 0 2021.11.01-11:06:09.968 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:06:09.969 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 0 2021.11.01-11:06:09.977 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:06:09.978 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 0 2021.11.01-11:06:09.979 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:06:09.981 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:09.983 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:09.986 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:06:09.986 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:09.989 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:09.991 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:06:09.993 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:09.995 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:09.995 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:09.997 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:09.998 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:09.998 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:06:09.999 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.000 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:06:10.000 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.000 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:06:10.001 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.002 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.003 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.003 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.003 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.004 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.004 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.004 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.004 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.005 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.005 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:06:10.006 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:06:10.006 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:06:10.006 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.007 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:06:10.007 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.007 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.007 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.007 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.007 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.008 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:10.008 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.009 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:06:10.009 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.009 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:06:10.010 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.010 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:06:10.010 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:10.011 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:06:10.011 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:06:10.012 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:06:10.013 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:06:18.075 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 0 2021.11.01-11:06:18.075 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 0 2021.11.01-11:06:18.097 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 0 2021.11.01-11:06:18.098 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 0 2021.11.01-11:06:18.099 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 0 2021.11.01-11:06:18.099 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 0 2021.11.01-11:06:18.113 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 0 2021.11.01-11:06:18.114 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 0 2021.11.01-11:06:18.114 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 0 2021.11.01-11:06:18.114 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 0 2021.11.01-11:06:18.115 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 0 2021.11.01-11:06:18.115 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 0 2021.11.01-11:06:18.115 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 0 2021.11.01-11:06:18.115 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 0 2021.11.01-11:06:18.116 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 0 2021.11.01-11:06:18.117 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 0 2021.11.01-11:06:18.122 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:06:18.127 info  [caliper] [report-builder] +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |--------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for SELLER round 1 | 10000 | 0 | 1277.6 | 1.45 | 0.29 | 0.93 | 1230.9 | +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:06:18.130 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:06:18.132 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 58.39 | 14.60 | 0.0303 | 0.0303 | 8.82 | 3.66 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 211.93 | 55.50 | 2.56 | 2.51 | 37.1 | 25.9 | 51.9 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 435.12 | 112.24 | 2.55 | 2.51 | 34.1 | 68.5 | 49.9 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 226.75 | 59.00 | 2.49 | 2.46 | 37.5 | 24.0 | 49.9 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:06:18.132 info  [caliper] [round-orchestrator] Finished round 1 (test STARTMATCHING fixed-rate for SELLER round 1) in 8.059 seconds 2021.11.01-11:06:18.132 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:06:23.133 info  [caliper] [round-orchestrator] Started round 2 (test STARTMATCHING fixed-rate for BUYER round 1) 2021.11.01-11:06:23.134 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:06:23.134 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:06:23.134 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:06:23.136 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.139 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.144 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.147 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.149 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.151 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.152 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.153 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.154 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.154 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.155 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.155 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.156 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.157 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.157 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.158 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:23.169 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.173 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.175 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.178 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.179 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.180 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.180 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.181 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.181 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.183 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.184 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.184 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.184 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.185 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.186 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:06:23.191 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:06:28.138 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.144 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.149 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.150 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.153 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.154 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.156 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.158 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.158 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.158 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.158 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.159 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.160 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.161 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.162 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.162 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:28.170 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:06:28.170 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 1 2021.11.01-11:06:28.173 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:06:28.174 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 1 2021.11.01-11:06:28.176 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:06:28.176 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 1 2021.11.01-11:06:28.178 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:06:28.178 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 1 2021.11.01-11:06:28.180 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:06:28.180 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 1 2021.11.01-11:06:28.181 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:06:28.181 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:06:28.181 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 1 2021.11.01-11:06:28.181 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:06:28.182 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 1 2021.11.01-11:06:28.182 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:06:28.182 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 1 2021.11.01-11:06:28.183 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 1 2021.11.01-11:06:28.184 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:06:28.184 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:06:28.185 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 1 2021.11.01-11:06:28.185 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:06:28.185 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:06:28.185 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 1 2021.11.01-11:06:28.185 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 1 2021.11.01-11:06:28.185 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 1 2021.11.01-11:06:28.186 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:06:28.186 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:06:28.186 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 1 2021.11.01-11:06:28.186 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 1 2021.11.01-11:06:28.191 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:06:28.191 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 1 2021.11.01-11:06:28.192 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:06:28.193 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.194 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.195 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:06:28.195 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.196 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.197 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:06:28.197 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.198 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.198 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:06:28.198 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.200 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.200 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.200 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:06:28.201 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.201 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:06:28.203 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.204 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.205 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.205 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:06:28.206 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.206 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:06:28.207 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.208 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.208 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.208 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.209 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.209 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.209 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:06:28.209 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.209 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.209 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.209 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.209 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:06:28.210 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:06:28.210 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.210 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:06:28.210 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.210 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.210 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:06:28.210 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.211 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:28.211 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:06:28.211 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.212 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.212 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:06:28.212 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:28.212 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:06:28.212 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:06:37.372 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 1 2021.11.01-11:06:37.373 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 1 2021.11.01-11:06:37.375 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 1 2021.11.01-11:06:37.375 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 1 2021.11.01-11:06:37.376 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 1 2021.11.01-11:06:37.378 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 1 2021.11.01-11:06:37.383 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 1 2021.11.01-11:06:37.385 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 1 2021.11.01-11:06:37.387 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 1 2021.11.01-11:06:37.387 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 1 2021.11.01-11:06:37.388 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 1 2021.11.01-11:06:37.388 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 1 2021.11.01-11:06:37.388 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 1 2021.11.01-11:06:37.389 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 1 2021.11.01-11:06:37.389 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 1 2021.11.01-11:06:37.390 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 1 2021.11.01-11:06:37.393 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:06:37.395 info  [caliper] [report-builder] +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |-------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for BUYER round 1 | 10000 | 0 | 1251.6 | 3.98 | 0.72 | 2.28 | 1087.9 | +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:06:37.396 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:06:37.399 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 922.78 | 588.76 | 0.0303 | 0.0303 | 14.9 | 2.48 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 126.78 | 79.82 | 2.58 | 2.56 | 36.3 | 24.9 | 19.9 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 289.88 | 193.45 | 2.59 | 2.58 | 36.4 | 66.6 | 19.9 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 121.48 | 70.20 | 2.51 | 2.50 | 32.4 | 20.7 | 16.6 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:06:37.400 info  [caliper] [round-orchestrator] Finished round 2 (test STARTMATCHING fixed-rate for BUYER round 1) in 9.156 seconds 2021.11.01-11:06:37.400 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:06:44.425 info  [caliper] [round-orchestrator] Started round 3 (test STARTMATCHING fixed-rate for SELLER round 2) 2021.11.01-11:06:44.426 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:06:44.426 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:06:44.426 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:06:44.427 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.433 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.434 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.436 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.438 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.441 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.442 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.443 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.444 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.444 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.444 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.446 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.446 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.447 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.447 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.448 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:06:44.461 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.462 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.463 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.466 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.468 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.470 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.470 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.471 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.472 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.473 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.473 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.474 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.475 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.476 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.477 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:06:44.480 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:06:49.432 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.434 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.438 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.438 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.438 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.442 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.446 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.446 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.446 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.446 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.448 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.450 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.450 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.450 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.450 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.449 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:06:49.462 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:06:49.462 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 2 2021.11.01-11:06:49.462 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:06:49.463 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 2 2021.11.01-11:06:49.464 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:06:49.465 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 2 2021.11.01-11:06:49.467 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:06:49.467 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 2 2021.11.01-11:06:49.469 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:06:49.469 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 2 2021.11.01-11:06:49.471 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:06:49.471 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:06:49.471 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 2 2021.11.01-11:06:49.472 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:06:49.472 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 2 2021.11.01-11:06:49.472 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 2 2021.11.01-11:06:49.473 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:06:49.473 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 2 2021.11.01-11:06:49.474 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:06:49.474 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:06:49.474 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 2 2021.11.01-11:06:49.474 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 2 2021.11.01-11:06:49.475 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:06:49.476 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 2 2021.11.01-11:06:49.476 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:06:49.477 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 2 2021.11.01-11:06:49.477 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:06:49.477 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:06:49.477 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 2 2021.11.01-11:06:49.477 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 2 2021.11.01-11:06:49.481 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:06:49.481 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 2 2021.11.01-11:06:49.482 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:06:49.483 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.484 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.484 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:06:49.485 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.486 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.486 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:06:49.487 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.487 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.488 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:06:49.490 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.491 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.491 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:06:49.492 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.493 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.493 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.494 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:06:49.495 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.495 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.496 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:06:49.496 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.497 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.497 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.497 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:06:49.498 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.498 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.498 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.498 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.498 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.498 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.498 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:06:49.499 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.499 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:06:49.499 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:06:49.500 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:06:49.500 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:06:49.500 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.500 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:06:49.500 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:06:49.501 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:06:57.585 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 2 2021.11.01-11:06:57.587 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 2 2021.11.01-11:06:57.587 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 2 2021.11.01-11:06:57.587 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 2 2021.11.01-11:06:57.587 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 2 2021.11.01-11:06:57.588 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 2 2021.11.01-11:06:57.588 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 2 2021.11.01-11:06:57.589 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 2 2021.11.01-11:06:57.590 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 2 2021.11.01-11:06:57.590 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 2 2021.11.01-11:06:57.590 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 2 2021.11.01-11:06:57.592 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 2 2021.11.01-11:06:57.592 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 2 2021.11.01-11:06:57.592 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 2 2021.11.01-11:06:57.592 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 2 2021.11.01-11:06:57.592 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 2 2021.11.01-11:06:57.596 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:06:57.599 info  [caliper] [report-builder] +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |--------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for SELLER round 2 | 10000 | 0 | 1299.0 | 1.46 | 0.40 | 0.90 | 1233.8 | +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:06:57.599 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:06:57.602 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 80.63 | 45.88 | 0.0303 | 0.0303 | 13.5 | 5.75 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 140.86 | 67.74 | 2.64 | 2.61 | 53.3 | 36.0 | 28.7 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 400.15 | 233.56 | 2.65 | 2.62 | 49.3 | 94.0 | 28.7 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 232.09 | 126.87 | 2.59 | 2.56 | 52.2 | 36.0 | 28.7 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:06:57.603 info  [caliper] [round-orchestrator] Finished round 3 (test STARTMATCHING fixed-rate for SELLER round 2) in 8.082 seconds 2021.11.01-11:06:57.603 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:07:04.629 info  [caliper] [round-orchestrator] Started round 4 (test STARTMATCHING fixed-rate for BUYER round 2) 2021.11.01-11:07:04.630 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:07:04.630 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:07:04.630 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:07:04.631 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.635 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.637 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.639 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.641 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.643 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.645 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.646 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.647 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.647 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.648 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.648 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.648 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.650 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.650 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.650 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:04.663 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.666 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.669 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.669 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.669 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.671 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.672 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.673 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.673 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.674 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.675 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.676 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.677 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.678 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.678 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:07:04.679 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:07:09.635 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.638 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.642 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.642 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.642 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.646 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.650 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.650 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.650 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.650 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.650 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.650 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.652 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.653 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.654 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.654 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:09.663 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:07:09.664 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 3 2021.11.01-11:07:09.667 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:07:09.667 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 3 2021.11.01-11:07:09.669 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:07:09.670 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:07:09.670 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 3 2021.11.01-11:07:09.670 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:07:09.670 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 3 2021.11.01-11:07:09.671 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 3 2021.11.01-11:07:09.672 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:07:09.672 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 3 2021.11.01-11:07:09.673 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:07:09.673 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 3 2021.11.01-11:07:09.674 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:07:09.674 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:07:09.674 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 3 2021.11.01-11:07:09.674 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 3 2021.11.01-11:07:09.675 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:07:09.675 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 3 2021.11.01-11:07:09.675 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:07:09.676 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 3 2021.11.01-11:07:09.677 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:07:09.677 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 3 2021.11.01-11:07:09.678 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:07:09.678 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 3 2021.11.01-11:07:09.679 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:07:09.679 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:07:09.679 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 3 2021.11.01-11:07:09.679 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 3 2021.11.01-11:07:09.680 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:07:09.680 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 3 2021.11.01-11:07:09.680 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:07:09.681 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.682 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.683 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:07:09.684 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.685 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.686 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:07:09.686 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.687 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.687 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.688 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:07:09.688 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.688 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.689 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:07:09.689 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.690 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.690 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:07:09.691 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.691 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.691 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.692 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.692 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.692 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.692 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:07:09.692 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.692 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.693 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:07:09.693 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.693 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:07:09.693 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.693 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.694 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.694 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.694 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.694 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.694 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:07:09.694 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.694 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:07:09.694 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:07:09.694 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:07:09.694 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:09.695 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:07:09.696 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:07:18.529 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 3 2021.11.01-11:07:18.529 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 3 2021.11.01-11:07:18.530 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 3 2021.11.01-11:07:18.533 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 3 2021.11.01-11:07:18.537 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 3 2021.11.01-11:07:18.543 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 3 2021.11.01-11:07:18.544 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 3 2021.11.01-11:07:18.544 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 3 2021.11.01-11:07:18.552 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 3 2021.11.01-11:07:18.557 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 3 2021.11.01-11:07:18.559 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 3 2021.11.01-11:07:18.562 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 3 2021.11.01-11:07:18.563 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 3 2021.11.01-11:07:18.566 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 3 2021.11.01-11:07:18.566 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 3 2021.11.01-11:07:18.593 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 3 2021.11.01-11:07:18.593 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:07:18.595 info  [caliper] [report-builder] +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |-------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for BUYER round 2 | 10000 | 0 | 1271.1 | 3.36 | 0.72 | 1.97 | 1122.7 | +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:07:18.596 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:07:18.599 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 804.60 | 519.17 | 0.0303 | 0.0303 | 15.8 | 2.51 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 163.85 | 58.96 | 2.68 | 2.67 | 36.3 | 24.9 | 19.9 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 304.48 | 201.36 | 2.68 | 2.66 | 36.6 | 76.1 | 19.9 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 150.00 | 93.37 | 2.64 | 2.62 | 36.3 | 23.9 | 19.9 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:07:18.600 info  [caliper] [round-orchestrator] Finished round 4 (test STARTMATCHING fixed-rate for BUYER round 2) in 8.831 seconds 2021.11.01-11:07:18.600 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:07:25.626 info  [caliper] [round-orchestrator] Started round 5 (test STARTMATCHING fixed-rate for SELLER round 3) 2021.11.01-11:07:25.626 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:07:25.626 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:07:25.627 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:07:25.627 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.630 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.632 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.633 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.634 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.635 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.639 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.640 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.640 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.641 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.641 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.642 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.642 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.643 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.643 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.644 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:25.659 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.661 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.661 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.661 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.662 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.662 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.666 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.669 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.671 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.671 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.672 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.672 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.673 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.673 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.674 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:07:25.675 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:07:30.629 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.632 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.634 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.635 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.636 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.637 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.641 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.642 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.642 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.643 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.644 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.644 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.644 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.645 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.645 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.646 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:30.659 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:07:30.660 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 4 2021.11.01-11:07:30.662 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:07:30.662 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:07:30.662 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:07:30.662 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 4 2021.11.01-11:07:30.662 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:07:30.663 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 4 2021.11.01-11:07:30.663 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:07:30.663 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 4 2021.11.01-11:07:30.663 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 4 2021.11.01-11:07:30.663 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 4 2021.11.01-11:07:30.666 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:07:30.667 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 4 2021.11.01-11:07:30.668 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:07:30.669 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 4 2021.11.01-11:07:30.671 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:07:30.671 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:07:30.672 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 4 2021.11.01-11:07:30.672 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 4 2021.11.01-11:07:30.673 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:07:30.673 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:07:30.673 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 4 2021.11.01-11:07:30.674 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:07:30.674 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:07:30.674 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 4 2021.11.01-11:07:30.674 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 4 2021.11.01-11:07:30.674 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 4 2021.11.01-11:07:30.675 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:07:30.675 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 4 2021.11.01-11:07:30.675 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:07:30.676 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 4 2021.11.01-11:07:30.676 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:07:30.677 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.678 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.678 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:07:30.679 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.680 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.680 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.681 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:07:30.682 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.682 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:07:30.683 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.684 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.685 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.685 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:07:30.686 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.686 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.686 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:07:30.687 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.687 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:07:30.687 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.688 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.688 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:07:30.689 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.689 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.689 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.690 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.690 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.690 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.690 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.691 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.691 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:07:30.691 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.691 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:07:30.691 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.691 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.691 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.692 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.692 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:07:30.693 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:07:30.693 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:07:30.693 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:07:30.693 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:30.694 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:07:38.767 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 4 2021.11.01-11:07:38.767 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 4 2021.11.01-11:07:38.768 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 4 2021.11.01-11:07:38.768 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 4 2021.11.01-11:07:38.768 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 4 2021.11.01-11:07:38.769 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 4 2021.11.01-11:07:38.769 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 4 2021.11.01-11:07:38.770 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 4 2021.11.01-11:07:38.770 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 4 2021.11.01-11:07:38.770 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 4 2021.11.01-11:07:38.770 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 4 2021.11.01-11:07:38.771 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 4 2021.11.01-11:07:38.771 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 4 2021.11.01-11:07:38.771 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 4 2021.11.01-11:07:38.771 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 4 2021.11.01-11:07:38.772 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 4 2021.11.01-11:07:38.776 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:07:38.777 info  [caliper] [report-builder] +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |--------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for SELLER round 3 | 10000 | 0 | 1292.3 | 1.43 | 0.35 | 0.92 | 1235.8 | +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:07:38.778 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:07:38.779 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 78.90 | 48.48 | 0.0303 | 0.0303 | 15.8 | 6.62 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 241.56 | 125.05 | 2.72 | 2.71 | 52.0 | 36.0 | 84.7 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 460.81 | 282.67 | 2.76 | 2.73 | 49.1 | 92.2 | 84.7 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 245.64 | 161.52 | 2.70 | 2.69 | 45.9 | 31.3 | 81.3 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:07:38.779 info  [caliper] [round-orchestrator] Finished round 5 (test STARTMATCHING fixed-rate for SELLER round 3) in 8.071 seconds 2021.11.01-11:07:38.779 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:07:45.804 info  [caliper] [round-orchestrator] Started round 6 (test STARTMATCHING fixed-rate for BUYER round 3) 2021.11.01-11:07:45.804 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:07:45.805 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:07:45.805 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:07:45.806 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.809 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.813 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.815 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.816 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.817 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.818 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.819 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.819 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.819 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.819 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.820 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.821 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.822 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.822 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.822 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:07:45.837 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.838 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.843 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.844 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.844 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.844 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.845 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.845 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.845 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.847 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.848 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.848 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.849 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.850 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.851 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:07:45.852 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:07:50.807 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.810 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.815 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.816 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.818 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.819 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.820 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.820 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.821 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.821 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.822 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.822 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.823 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.823 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.823 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.824 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:07:50.838 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:07:50.838 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:07:50.839 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 5 2021.11.01-11:07:50.839 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 5 2021.11.01-11:07:50.844 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:07:50.844 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 5 2021.11.01-11:07:50.845 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:07:50.845 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:07:50.845 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:07:50.845 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:07:50.845 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:07:50.845 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 5 2021.11.01-11:07:50.845 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 5 2021.11.01-11:07:50.845 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 5 2021.11.01-11:07:50.845 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 5 2021.11.01-11:07:50.845 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 5 2021.11.01-11:07:50.845 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:07:50.846 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 5 2021.11.01-11:07:50.848 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:07:50.848 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:07:50.849 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 5 2021.11.01-11:07:50.849 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:07:50.849 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 5 2021.11.01-11:07:50.850 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 5 2021.11.01-11:07:50.850 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:07:50.850 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:07:50.851 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 5 2021.11.01-11:07:50.851 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 5 2021.11.01-11:07:50.851 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:07:50.852 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 5 2021.11.01-11:07:50.853 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:07:50.853 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 5 2021.11.01-11:07:50.853 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:07:50.854 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.855 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.856 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:07:50.858 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.859 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.860 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:07:50.861 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.862 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.863 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:07:50.863 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.864 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.864 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:07:50.867 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.868 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.868 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.869 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.869 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:07:50.869 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.869 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:07:50.870 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.871 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.871 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.871 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.871 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.871 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:07:50.872 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.872 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:07:50.872 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.872 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:07:50.872 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.872 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.873 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.873 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:07:50.873 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.873 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.873 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.874 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:07:50.874 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:07:50.875 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.875 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:07:50.876 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:07:50.876 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:07:59.816 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 5 2021.11.01-11:07:59.818 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 5 2021.11.01-11:07:59.819 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 5 2021.11.01-11:07:59.821 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 5 2021.11.01-11:07:59.827 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 5 2021.11.01-11:07:59.828 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 5 2021.11.01-11:07:59.829 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 5 2021.11.01-11:07:59.831 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 5 2021.11.01-11:07:59.831 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 5 2021.11.01-11:07:59.832 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 5 2021.11.01-11:07:59.832 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 5 2021.11.01-11:07:59.833 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 5 2021.11.01-11:07:59.833 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 5 2021.11.01-11:07:59.833 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 5 2021.11.01-11:07:59.834 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 5 2021.11.01-11:07:59.861 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 5 2021.11.01-11:07:59.862 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:07:59.863 info  [caliper] [report-builder] +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |-------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for BUYER round 3 | 10000 | 0 | 1224.1 | 4.22 | 0.72 | 2.18 | 1110.7 | +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:07:59.864 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:07:59.866 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 952.39 | 560.93 | 0.0303 | 0.0303 | 13.3 | 2.16 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 128.43 | 76.17 | 2.75 | 2.74 | 36.3 | 25.0 | 78.6 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 303.73 | 198.39 | 2.78 | 2.77 | 39.8 | 72.3 | 78.6 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 118.57 | 78.21 | 2.74 | 2.73 | 36.8 | 25.0 | 78.6 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:07:59.867 info  [caliper] [round-orchestrator] Finished round 6 (test STARTMATCHING fixed-rate for BUYER round 3) in 8.938 seconds 2021.11.01-11:07:59.867 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:08:06.892 info  [caliper] [round-orchestrator] Started round 7 (test STARTMATCHING fixed-rate for SELLER round 4) 2021.11.01-11:08:06.892 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:08:06.892 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:08:06.892 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:08:06.895 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.897 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.899 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.900 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.902 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.903 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.904 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.905 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.906 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.906 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.907 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.907 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.907 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.908 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.908 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.908 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:06.925 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.926 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.927 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.927 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.928 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.928 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.928 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.931 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.932 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.932 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.935 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.935 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.936 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.936 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.937 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:08:06.937 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:08:11.897 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.898 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.902 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.904 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.902 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.905 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.906 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.908 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.909 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.909 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.908 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.910 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.910 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.910 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.910 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.911 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:11.926 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:08:11.927 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 6 2021.11.01-11:08:11.927 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:08:11.927 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 6 2021.11.01-11:08:11.928 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:08:11.928 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:08:11.928 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 6 2021.11.01-11:08:11.928 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 6 2021.11.01-11:08:11.928 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:08:11.929 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:08:11.929 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 6 2021.11.01-11:08:11.929 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 6 2021.11.01-11:08:11.929 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:08:11.930 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 6 2021.11.01-11:08:11.932 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:08:11.932 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:08:11.932 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 6 2021.11.01-11:08:11.932 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 6 2021.11.01-11:08:11.932 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:08:11.933 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 6 2021.11.01-11:08:11.936 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:08:11.936 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:08:11.936 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:08:11.936 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 6 2021.11.01-11:08:11.936 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 6 2021.11.01-11:08:11.936 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:08:11.936 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 6 2021.11.01-11:08:11.937 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 6 2021.11.01-11:08:11.938 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:08:11.938 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 6 2021.11.01-11:08:11.938 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:08:11.939 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:08:11.939 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 6 2021.11.01-11:08:11.940 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.941 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.941 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.941 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:08:11.942 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.942 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:08:11.943 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.944 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.944 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.944 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:08:11.945 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.945 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.945 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:08:11.946 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.947 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.948 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.948 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.948 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.949 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.949 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:08:11.949 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:08:11.949 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.949 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.949 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.949 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.950 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.950 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:08:11.950 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.950 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:08:11.950 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:08:11.950 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.951 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.951 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.951 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:08:11.951 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.952 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.952 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:08:11.952 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:08:11.952 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.952 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.952 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:11.953 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:08:11.953 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.954 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.954 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:08:11.954 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:08:11.954 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:11.955 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:08:20.040 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 6 2021.11.01-11:08:20.040 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 6 2021.11.01-11:08:20.040 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 6 2021.11.01-11:08:20.040 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 6 2021.11.01-11:08:20.040 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 6 2021.11.01-11:08:20.040 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 6 2021.11.01-11:08:20.042 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 6 2021.11.01-11:08:20.043 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 6 2021.11.01-11:08:20.048 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:08:20.048 info  [caliper] [report-builder] +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |--------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for SELLER round 4 | 10000 | 0 | 1296.3 | 1.44 | 0.37 | 0.90 | 1234.7 | +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:08:20.049 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:08:20.050 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 78.33 | 42.43 | 0.0303 | 0.0303 | 13.4 | 5.62 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 168.92 | 78.76 | 2.79 | 2.77 | 51.9 | 36.0 | 90.1 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 398.07 | 263.71 | 2.85 | 2.81 | 47.7 | 92.1 | 90.1 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 206.10 | 114.70 | 2.79 | 2.78 | 51.9 | 36.1 | 90.1 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:08:20.051 info  [caliper] [round-orchestrator] Finished round 7 (test STARTMATCHING fixed-rate for SELLER round 4) in 8.083 seconds 2021.11.01-11:08:20.051 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:08:27.075 info  [caliper] [round-orchestrator] Started round 8 (test STARTMATCHING fixed-rate for BUYER round 4) 2021.11.01-11:08:27.076 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:08:27.076 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:08:27.076 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:08:27.077 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.080 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.083 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.084 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.085 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.087 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.088 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.089 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.089 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.090 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.092 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.092 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.093 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.093 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.094 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.094 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:27.112 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.112 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.112 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.113 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.113 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.115 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.115 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.115 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.115 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.119 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.119 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.120 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.121 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.121 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.122 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:08:27.125 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:08:32.081 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.082 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.085 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.086 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.088 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.089 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.091 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.091 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.092 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.093 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.094 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.094 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.094 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.096 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.096 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.097 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:32.111 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:08:32.112 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 7 2021.11.01-11:08:32.112 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:08:32.113 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 7 2021.11.01-11:08:32.113 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:08:32.113 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:08:32.114 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 7 2021.11.01-11:08:32.114 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 7 2021.11.01-11:08:32.115 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:08:32.115 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:08:32.116 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:08:32.115 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:08:32.114 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:08:32.116 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 7 2021.11.01-11:08:32.116 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 7 2021.11.01-11:08:32.116 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 7 2021.11.01-11:08:32.116 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 7 2021.11.01-11:08:32.117 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 7 2021.11.01-11:08:32.120 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:08:32.120 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:08:32.120 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 7 2021.11.01-11:08:32.120 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:08:32.121 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 7 2021.11.01-11:08:32.121 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:08:32.121 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 7 2021.11.01-11:08:32.121 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 7 2021.11.01-11:08:32.121 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:08:32.122 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 7 2021.11.01-11:08:32.123 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:08:32.123 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 7 2021.11.01-11:08:32.126 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:08:32.126 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:08:32.126 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 7 2021.11.01-11:08:32.127 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.128 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.129 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.129 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.129 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:08:32.130 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:08:32.130 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.131 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.131 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.131 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:08:32.132 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.132 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.133 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.133 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:08:32.133 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:08:32.133 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.134 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.135 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:08:32.135 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.135 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.136 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.136 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.136 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.136 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:08:32.136 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.137 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.137 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.137 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:08:32.137 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.137 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.138 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.138 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.138 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.138 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:08:32.138 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:08:32.138 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:08:32.138 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.139 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.139 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:08:32.139 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.139 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:08:32.139 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.140 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:08:32.140 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:32.140 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.141 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:32.141 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:08:32.142 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:08:41.000 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 7 2021.11.01-11:08:41.000 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 7 2021.11.01-11:08:41.004 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 7 2021.11.01-11:08:41.008 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 7 2021.11.01-11:08:41.008 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 7 2021.11.01-11:08:41.009 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 7 2021.11.01-11:08:41.012 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 7 2021.11.01-11:08:41.016 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 7 2021.11.01-11:08:41.019 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 7 2021.11.01-11:08:41.023 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 7 2021.11.01-11:08:41.025 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 7 2021.11.01-11:08:41.025 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 7 2021.11.01-11:08:41.029 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 7 2021.11.01-11:08:41.033 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 7 2021.11.01-11:08:41.035 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 7 2021.11.01-11:08:41.039 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 7 2021.11.01-11:08:41.040 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:08:41.042 info  [caliper] [report-builder] +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |-------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for BUYER round 4 | 10000 | 0 | 1266.0 | 3.08 | 0.69 | 1.81 | 1122.7 | +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:08:41.042 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:08:41.045 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [B] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 866.84 | 519.25 | 0.0303 | 0.0303 | 15.5 | 2.53 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer2.org1.example.com | 130.33 | 86.34 | 2.83 | 2.82 | 42.4 | 29.2 | 26.0 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer0.org1.example.com | 323.89 | 211.32 | 2.88 | 2.86 | 36.7 | 75.7 | 26.0 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|---------------| | peer1.org1.example.com | 140.35 | 93.55 | 2.82 | 2.81 | 36.3 | 24.0 | 19.9 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+---------------+ 2021.11.01-11:08:41.045 info  [caliper] [round-orchestrator] Finished round 8 (test STARTMATCHING fixed-rate for BUYER round 4) in 8.857 seconds 2021.11.01-11:08:41.045 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:08:48.068 info  [caliper] [round-orchestrator] Started round 9 (test STARTMATCHING fixed-rate for SELLER round 5) 2021.11.01-11:08:48.068 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:08:48.068 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:08:48.068 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:08:48.069 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.071 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.075 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.075 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.077 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.078 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.079 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.079 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.080 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.080 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.080 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.081 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.082 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.083 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.083 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.083 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:08:48.102 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.102 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.103 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.103 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.103 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.104 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.105 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.106 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.107 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.109 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.111 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.111 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.112 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.114 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.115 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:08:48.116 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:08:53.072 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.074 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.077 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.078 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.079 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.081 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.081 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.081 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.082 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.083 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.083 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.084 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.084 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.085 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.085 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.086 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:08:53.102 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:08:53.103 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:08:53.103 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 8 2021.11.01-11:08:53.103 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:08:53.103 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:08:53.103 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:08:53.104 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 8 2021.11.01-11:08:53.104 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 8 2021.11.01-11:08:53.104 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 8 2021.11.01-11:08:53.104 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 8 2021.11.01-11:08:53.105 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:08:53.106 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 8 2021.11.01-11:08:53.105 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:08:53.106 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:08:53.106 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 8 2021.11.01-11:08:53.107 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 8 2021.11.01-11:08:53.108 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:08:53.109 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 8 2021.11.01-11:08:53.109 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:08:53.110 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 8 2021.11.01-11:08:53.110 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:08:53.111 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 8 2021.11.01-11:08:53.112 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:08:53.113 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 8 2021.11.01-11:08:53.113 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:08:53.114 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 8 2021.11.01-11:08:53.115 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:08:53.116 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 8 2021.11.01-11:08:53.116 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:08:53.116 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 8 2021.11.01-11:08:53.117 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:08:53.118 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 8 2021.11.01-11:08:53.123 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:08:53.124 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.125 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.126 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.126 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:08:53.127 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.127 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:08:53.128 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.129 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.129 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.130 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:08:53.130 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.130 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.131 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.131 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.131 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:08:53.131 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.132 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.132 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.132 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:08:53.132 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:08:53.132 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.133 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.133 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.133 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.133 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.133 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.133 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.133 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.134 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:08:53.134 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.134 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:08:53.134 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:08:53.135 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.135 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:08:53.136 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:08:53.136 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.136 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:08:53.136 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.136 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:08:53.137 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:08:53.137 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:08:53.137 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:09:01.235 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 8 2021.11.01-11:09:01.237 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 8 2021.11.01-11:09:01.237 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 8 2021.11.01-11:09:01.239 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 8 2021.11.01-11:09:01.240 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 8 2021.11.01-11:09:01.240 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 8 2021.11.01-11:09:01.240 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 8 2021.11.01-11:09:01.241 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 8 2021.11.01-11:09:01.242 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 8 2021.11.01-11:09:01.242 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 8 2021.11.01-11:09:01.243 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 8 2021.11.01-11:09:01.243 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 8 2021.11.01-11:09:01.244 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 8 2021.11.01-11:09:01.245 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 8 2021.11.01-11:09:01.245 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 8 2021.11.01-11:09:01.250 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 8 2021.11.01-11:09:01.250 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:09:01.251 info  [caliper] [report-builder] +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |--------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for SELLER round 5 | 10000 | 0 | 1298.9 | 1.37 | 0.40 | 0.87 | 1231.1 | +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:09:01.251 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:09:01.253 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+----------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [KB] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 82.20 | 46.13 | 0.0303 | 0.0303 | 13.5 | 5.84 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer2.org1.example.com | 217.69 | 136.94 | 2.86 | 2.85 | 45.6 | 31.8 | 25.3 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer0.org1.example.com | 405.69 | 268.14 | 2.92 | 2.90 | 49.4 | 92.2 | 28.6 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer1.org1.example.com | 199.58 | 116.41 | 2.86 | 2.84 | 51.7 | 35.9 | 28.6 | 4.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+----------------+ 2021.11.01-11:09:01.253 info  [caliper] [round-orchestrator] Finished round 9 (test STARTMATCHING fixed-rate for SELLER round 5) in 8.096 seconds 2021.11.01-11:09:01.253 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:09:08.275 info  [caliper] [round-orchestrator] Started round 10 (test STARTMATCHING fixed-rate for BUYER round 5) 2021.11.01-11:09:08.276 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:09:08.276 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:09:08.276 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:09:08.277 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.279 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.283 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.284 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.285 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.287 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.288 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.288 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.288 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.289 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.289 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.290 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.291 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.291 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.292 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.292 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:08.308 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.311 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.312 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.315 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.315 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.316 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.316 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.317 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.317 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.317 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.318 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.319 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.319 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.319 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.320 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:09:08.321 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:09:13.280 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.282 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.284 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.287 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.287 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.289 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.289 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.290 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.290 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.290 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.291 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.292 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.293 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.294 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.294 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.294 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:13.308 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:09:13.308 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 9 2021.11.01-11:09:13.311 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:09:13.312 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 9 2021.11.01-11:09:13.312 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:09:13.313 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 9 2021.11.01-11:09:13.316 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:09:13.316 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:09:13.316 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 9 2021.11.01-11:09:13.316 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 9 2021.11.01-11:09:13.316 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:09:13.316 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:09:13.317 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 9 2021.11.01-11:09:13.317 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 9 2021.11.01-11:09:13.317 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:09:13.318 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 9 2021.11.01-11:09:13.318 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:09:13.318 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:09:13.318 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 9 2021.11.01-11:09:13.319 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 9 2021.11.01-11:09:13.319 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:09:13.319 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:09:13.320 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:09:13.320 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:09:13.320 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 9 2021.11.01-11:09:13.320 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 9 2021.11.01-11:09:13.320 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 9 2021.11.01-11:09:13.320 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 9 2021.11.01-11:09:13.320 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:09:13.321 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 9 2021.11.01-11:09:13.322 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:09:13.323 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 9 2021.11.01-11:09:13.323 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:09:13.324 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.325 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.325 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:09:13.326 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.327 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.327 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:09:13.328 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.329 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.329 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:09:13.331 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.332 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.332 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.332 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.333 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.333 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:09:13.333 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:09:13.333 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.334 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.334 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:09:13.334 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.335 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.335 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.335 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.335 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.336 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:09:13.336 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.336 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.336 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:09:13.336 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.336 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.337 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:09:13.337 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.337 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:09:13.337 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.337 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.337 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.337 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.338 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:13.338 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:09:13.338 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:09:13.339 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:09:13.340 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:09:22.083 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 9 2021.11.01-11:09:22.085 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 9 2021.11.01-11:09:22.086 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 9 2021.11.01-11:09:22.089 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 9 2021.11.01-11:09:22.091 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 9 2021.11.01-11:09:22.097 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 9 2021.11.01-11:09:22.097 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 9 2021.11.01-11:09:22.100 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 9 2021.11.01-11:09:22.114 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 9 2021.11.01-11:09:22.114 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 9 2021.11.01-11:09:22.115 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 9 2021.11.01-11:09:22.115 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 9 2021.11.01-11:09:22.119 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 9 2021.11.01-11:09:22.120 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 9 2021.11.01-11:09:22.138 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 9 2021.11.01-11:09:22.141 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 9 2021.11.01-11:09:22.142 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:09:22.143 info  [caliper] [report-builder] +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |-------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for BUYER round 5 | 10000 | 0 | 1265.8 | 3.58 | 0.72 | 1.83 | 1134.6 | +-------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:09:22.143 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:09:22.146 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+----------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [KB] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 933.28 | 556.85 | 0.0303 | 0.0303 | 13.5 | 2.19 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer2.org1.example.com | 127.23 | 84.08 | 2.90 | 2.88 | 42.4 | 29.2 | 23.2 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer0.org1.example.com | 303.76 | 199.74 | 2.96 | 2.94 | 39.8 | 76.1 | 23.2 | 4.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer1.org1.example.com | 126.20 | 82.24 | 2.89 | 2.88 | 42.4 | 29.2 | 23.2 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+----------------+ 2021.11.01-11:09:22.146 info  [caliper] [round-orchestrator] Finished round 10 (test STARTMATCHING fixed-rate for BUYER round 5) in 8.742 seconds 2021.11.01-11:09:22.146 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:09:29.172 info  [caliper] [round-orchestrator] Started round 11 (test STARTMATCHING fixed-rate for SELLER round 6) 2021.11.01-11:09:29.172 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:09:29.172 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:09:29.173 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:09:29.173 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.176 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.179 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.184 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.187 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.188 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.190 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.191 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.191 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.192 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.192 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.192 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.193 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.193 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.195 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.194 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:29.200 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.208 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.210 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.213 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.216 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.218 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.218 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.218 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.219 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.220 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.220 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.221 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.221 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.224 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.225 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:09:29.228 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:09:34.176 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.178 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.181 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.185 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.190 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.190 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.192 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.193 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.193 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.193 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.193 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.194 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.194 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.195 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.197 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.197 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:34.201 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:09:34.201 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 10 2021.11.01-11:09:34.207 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:09:34.208 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 10 2021.11.01-11:09:34.210 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:09:34.210 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 10 2021.11.01-11:09:34.214 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:09:34.214 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 10 2021.11.01-11:09:34.217 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:09:34.217 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 10 2021.11.01-11:09:34.218 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:09:34.219 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:09:34.219 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 10 2021.11.01-11:09:34.219 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:09:34.219 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 10 2021.11.01-11:09:34.219 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:09:34.220 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 10 2021.11.01-11:09:34.220 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:09:34.220 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 10 2021.11.01-11:09:34.220 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:09:34.220 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 10 2021.11.01-11:09:34.221 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:09:34.221 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 10 2021.11.01-11:09:34.221 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 10 2021.11.01-11:09:34.221 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:09:34.222 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 10 2021.11.01-11:09:34.225 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:09:34.225 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 10 2021.11.01-11:09:34.226 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:09:34.226 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 10 2021.11.01-11:09:34.229 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:09:34.230 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 10 2021.11.01-11:09:34.230 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:09:34.231 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.232 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.232 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:09:34.233 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.235 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.235 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.235 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:09:34.236 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.236 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:09:34.236 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.238 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.238 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:09:34.239 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.240 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.240 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.240 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:09:34.241 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.242 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.242 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:09:34.242 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.243 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.243 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.243 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.244 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.244 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:09:34.244 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.244 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.244 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:09:34.244 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.244 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:09:34.245 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.245 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.245 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.245 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.245 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.245 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.246 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:09:34.246 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:09:34.246 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:34.246 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.246 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:09:34.246 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.247 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.247 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:09:34.247 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:09:34.247 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger 2021.11.01-11:09:34.248 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:34.249 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:09:42.330 info  [caliper] [message-handler] Handled successful "test" message for worker 6 and test round 10 2021.11.01-11:09:42.330 info  [caliper] [message-handler] Handled successful "test" message for worker 11 and test round 10 2021.11.01-11:09:42.330 info  [caliper] [message-handler] Handled successful "test" message for worker 0 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 2 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 3 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 8 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 4 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 13 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 5 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 7 and test round 10 2021.11.01-11:09:42.332 info  [caliper] [message-handler] Handled successful "test" message for worker 15 and test round 10 2021.11.01-11:09:42.333 info  [caliper] [message-handler] Handled successful "test" message for worker 14 and test round 10 2021.11.01-11:09:42.333 info  [caliper] [message-handler] Handled successful "test" message for worker 9 and test round 10 2021.11.01-11:09:42.333 info  [caliper] [message-handler] Handled successful "test" message for worker 1 and test round 10 2021.11.01-11:09:42.333 info  [caliper] [message-handler] Handled successful "test" message for worker 12 and test round 10 2021.11.01-11:09:42.334 info  [caliper] [message-handler] Handled successful "test" message for worker 10 and test round 10 2021.11.01-11:09:42.338 info  [caliper] [report-builder] ### Test result ### 2021.11.01-11:09:42.339 info  [caliper] [report-builder] +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ | Name | Succ | Fail | Send Rate (TPS) | Max Latency (s) | Min Latency (s) | Avg Latency (s) | Throughput (TPS) | |--------------------------------------------------|-------|------|-----------------|-----------------|-----------------|-----------------|------------------| | test STARTMATCHING fixed-rate for SELLER round 6 | 10000 | 0 | 1287.0 | 1.51 | 0.33 | 0.90 | 1234.7 | +--------------------------------------------------+-------+------+-----------------+-----------------+-----------------+-----------------+------------------+ 2021.11.01-11:09:42.339 info  [caliper] [report-builder] ### docker resource stats ###' 2021.11.01-11:09:42.341 info  [caliper] [report-builder] +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+----------------+ | Name | CPU%(max) | CPU%(avg) | Memory(max) [GB] | Memory(avg) [GB] | Traffic In [MB] | Traffic Out [MB] | Disc Write [MB] | Disc Read [KB] | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | dev-peer0.org1.example.com-OneOneEnergyTradingv27-v0 | 80.41 | 50.58 | 0.0303 | 0.0303 | 15.8 | 6.62 | 0.00 | 0.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer2.org1.example.com | 241.05 | 150.21 | 2.93 | 2.92 | 45.8 | 32.2 | 95.1 | 4.00 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer0.org1.example.com | 442.13 | 286.88 | 3.00 | 2.98 | 39.9 | 80.5 | 95.1 | 12.0 | |------------------------------------------------------|-----------|-----------|------------------|------------------|-----------------|------------------|-----------------|----------------| | peer1.org1.example.com | 218.07 | 146.65 | 2.93 | 2.92 | 46.1 | 31.9 | 95.1 | 0.00 | +------------------------------------------------------+-----------+-----------+------------------+------------------+-----------------+------------------+-----------------+----------------+ 2021.11.01-11:09:42.341 info  [caliper] [round-orchestrator] Finished round 11 (test STARTMATCHING fixed-rate for SELLER round 6) in 8.079 seconds 2021.11.01-11:09:42.341 info  [caliper] [round-orchestrator] Waiting 5 seconds for the next round... 2021.11.01-11:09:49.364 info  [caliper] [round-orchestrator] Started round 12 (test STARTMATCHING fixed-rate for BUYER round 6) 2021.11.01-11:09:49.364 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected, progressing to worker assignment phase. 2021.11.01-11:09:49.364 info  [caliper] [worker-orchestrator] Existing 16 connected workers detected are assigned, progressing to worker initialization phase. 2021.11.01-11:09:49.364 info  [caliper] [worker-orchestrator] Existing 16 prepared workers detected, progressing to test preparation phase. 2021.11.01-11:09:49.367 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.370 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.372 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.373 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.376 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.377 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.378 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.379 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.380 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.381 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.381 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.382 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.382 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.382 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.383 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.383 info  [caliper] [message-handler] Handling "prepare" message 2021.11.01-11:09:49.401 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.401 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.402 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.403 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.404 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.405 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.406 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.406 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.406 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.406 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.406 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.408 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.409 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.409 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.411 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase starting... 2021.11.01-11:09:49.412 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase starting... 2021.11.01-11:09:54.370 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.371 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.373 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.376 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.379 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.380 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.380 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.381 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.382 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.383 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.383 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.383 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.384 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.384 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.384 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.385 info  [caliper] [caliper-local-client] Initialization ongoing... 2021.11.01-11:09:54.401 info  [caliper] [caliper-local-client] Info: client 0 prepare test :initLedgerphase complete 2021.11.01-11:09:54.402 info  [caliper] [caliper-local-client] Info: client 1 prepare test :initLedgerphase complete 2021.11.01-11:09:54.402 info  [caliper] [caliper-local-client] Info: client 3 prepare test :initLedgerphase complete 2021.11.01-11:09:54.402 info  [caliper] [message-handler] Handled successful "prepare" message for worker 0 and test round 11 2021.11.01-11:09:54.402 info  [caliper] [message-handler] Handled successful "prepare" message for worker 1 and test round 11 2021.11.01-11:09:54.402 info  [caliper] [message-handler] Handled successful "prepare" message for worker 3 and test round 11 2021.11.01-11:09:54.403 info  [caliper] [caliper-local-client] Info: client 15 prepare test :initLedgerphase complete 2021.11.01-11:09:54.404 info  [caliper] [message-handler] Handled successful "prepare" message for worker 15 and test round 11 2021.11.01-11:09:54.404 info  [caliper] [caliper-local-client] Info: client 7 prepare test :initLedgerphase complete 2021.11.01-11:09:54.404 info  [caliper] [caliper-local-client] Info: client 9 prepare test :initLedgerphase complete 2021.11.01-11:09:54.405 info  [caliper] [message-handler] Handled successful "prepare" message for worker 7 and test round 11 2021.11.01-11:09:54.405 info  [caliper] [message-handler] Handled successful "prepare" message for worker 9 and test round 11 2021.11.01-11:09:54.406 info  [caliper] [caliper-local-client] Info: client 14 prepare test :initLedgerphase complete 2021.11.01-11:09:54.406 info  [caliper] [caliper-local-client] Info: client 6 prepare test :initLedgerphase complete 2021.11.01-11:09:54.407 info  [caliper] [caliper-local-client] Info: client 2 prepare test :initLedgerphase complete 2021.11.01-11:09:54.407 info  [caliper] [message-handler] Handled successful "prepare" message for worker 14 and test round 11 2021.11.01-11:09:54.407 info  [caliper] [message-handler] Handled successful "prepare" message for worker 6 and test round 11 2021.11.01-11:09:54.407 info  [caliper] [caliper-local-client] Info: client 12 prepare test :initLedgerphase complete 2021.11.01-11:09:54.407 info  [caliper] [caliper-local-client] Info: client 11 prepare test :initLedgerphase complete 2021.11.01-11:09:54.407 info  [caliper] [message-handler] Handled successful "prepare" message for worker 2 and test round 11 2021.11.01-11:09:54.408 info  [caliper] [message-handler] Handled successful "prepare" message for worker 12 and test round 11 2021.11.01-11:09:54.408 info  [caliper] [message-handler] Handled successful "prepare" message for worker 11 and test round 11 2021.11.01-11:09:54.408 info  [caliper] [caliper-local-client] Info: client 5 prepare test :initLedgerphase complete 2021.11.01-11:09:54.408 info  [caliper] [caliper-local-client] Info: client 4 prepare test :initLedgerphase complete 2021.11.01-11:09:54.409 info  [caliper] [message-handler] Handled successful "prepare" message for worker 5 and test round 11 2021.11.01-11:09:54.409 info  [caliper] [message-handler] Handled successful "prepare" message for worker 4 and test round 11 2021.11.01-11:09:54.409 info  [caliper] [caliper-local-client] Info: client 10 prepare test :initLedgerphase complete 2021.11.01-11:09:54.410 info  [caliper] [message-handler] Handled successful "prepare" message for worker 10 and test round 11 2021.11.01-11:09:54.412 info  [caliper] [caliper-local-client] Info: client 13 prepare test :initLedgerphase complete 2021.11.01-11:09:54.413 info  [caliper] [message-handler] Handled successful "prepare" message for worker 13 and test round 11 2021.11.01-11:09:54.413 info  [caliper] [caliper-local-client] Info: client 8 prepare test :initLedgerphase complete 2021.11.01-11:09:54.413 info  [caliper] [message-handler] Handled successful "prepare" message for worker 8 and test round 11 2021.11.01-11:09:54.414 info  [caliper] [worker-orchestrator] 16 workers prepared, progressing to test phase. 2021.11.01-11:09:54.414 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.415 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.416 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.416 info  [caliper] [caliper-local-client] Info: client 0 start test runFixedNumber():initLedger 2021.11.01-11:09:54.416 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.417 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.417 info  [caliper] [caliper-local-client] Info: client 1 start test runFixedNumber():initLedger 2021.11.01-11:09:54.418 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.418 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.418 info  [caliper] [caliper-local-client] Info: client 2 start test runFixedNumber():initLedger 2021.11.01-11:09:54.419 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.419 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.419 info  [caliper] [caliper-local-client] Info: client 3 start test runFixedNumber():initLedger 2021.11.01-11:09:54.420 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.420 info  [caliper] [caliper-local-client] Info: client 4 start test runFixedNumber():initLedger 2021.11.01-11:09:54.420 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.421 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.421 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.422 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.422 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.422 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.422 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.422 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.422 info  [caliper] [caliper-local-client] Info: client 5 start test runFixedNumber():initLedger 2021.11.01-11:09:54.422 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.423 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.423 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.423 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.423 info  [caliper] [caliper-local-client] Info: client 6 start test runFixedNumber():initLedger 2021.11.01-11:09:54.423 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.423 info  [caliper] [caliper-local-client] Info: client 10 start test runFixedNumber():initLedger 2021.11.01-11:09:54.423 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.424 info  [caliper] [caliper-local-client] Info: client 7 start test runFixedNumber():initLedger 2021.11.01-11:09:54.424 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.424 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.424 info  [caliper] [caliper-local-client] Info: client 8 start test runFixedNumber():initLedger 2021.11.01-11:09:54.424 info  [caliper] [caliper-local-client] Info: client 11 start test runFixedNumber():initLedger 2021.11.01-11:09:54.424 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.424 info  [caliper] [message-handler] Handling "test" message 2021.11.01-11:09:54.424 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.424 info  [caliper] [caliper-local-client] Info: client 9 start test runFixedNumber():initLedger 2021.11.01-11:09:54.425 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.425 info  [caliper] [caliper-local-client] Info: client 12 start test runFixedNumber():initLedger 2021.11.01-11:09:54.425 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.425 info  [caliper] [caliper-local-client] Info: client 14 start test runFixedNumber():initLedger 2021.11.01-11:09:54.426 info  [caliper] [caliper-local-client] txUpdateTime: 5000 2021.11.01-11:09:54.426 info  [caliper] [caliper-local-client] Info: client 15 start test runFixedNumber():initLedger 2021.11.01-11:09:54.427 info  [caliper] [caliper-local-client] Info: client 13 start test runFixedNumber():initLedger