search

hyperledger-caliper / caliper

A blockchain benchmark framework to measure performance of multiple blockchain solutions https://wiki.hyperledger.org/display/caliper
https://hyperledger-caliper.github.io/caliper/
Apache License 2.0
653 stars 405 forks source link

Bootstrapping Caliper repo with Benchmark=besu - Error:this.web3.eth.getChainID is not a function #838

Closed cezarvatafu closed 4 years ago

cezarvatafu commented 4 years ago

Context

Bootstrapping the Caliper repository with BENCHMARK=besu

BENCHMARK=fabric ./.travis/benchmark-integration-test-direct.sh

Expected Behavior

Linting checks Licence header checks Unit tests Running sample benchmarks

Actual Behavior

After several npm packages errors which were not intuitive to debug I managed to bootstrap the repo :

cezar@cezar-VirtualBox:~/caliper$ BENCHMARK=besu ./.travis/benchmark-integration-test-direct.sh added 8 packages from 1 contributor, removed 2429 packages, updated 15 packages and audited 1011 packages in 23.728s

28 packages are looking for funding run npm fund for details

found 1 low severity vulnerability run npm audit fix to fix them, or npm audit for details

caliper@0.4.0-unstable repoclean /home/cezar/caliper lerna clean "--yes"

lerna notice cli v3.20.2 lerna info clean removing /home/cezar/caliper/packages/caliper-burrow/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-cli/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-core/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-ethereum/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-fabric/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-fisco-bcos/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-generator/generator-caliper/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-gui-dashboard/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-gui-server/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-iroha/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-publish/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-sawtooth/node_modules lerna info clean removing /home/cezar/caliper/packages/caliper-tests-integration/node_modules lerna success clean finished

caliper@0.4.0-unstable bootstrap /home/cezar/caliper lerna bootstrap

lerna notice cli v3.20.2 lerna info Bootstrapping 13 packages lerna WARN EHOIST_ROOT_VERSION The repository root depends on web3@^1.2.7, which differs from the more common web3@0.20.7. lerna WARN EHOIST_PKG_VERSION "@hyperledger/caliper-ethereum" package depends on web3@1.2.2, which differs from the hoisted web3@^1.2.7. lerna WARN EHOIST_PKG_VERSION "@hyperledger/caliper-fisco-bcos" package depends on web3@0.20.7, which differs from the hoisted web3@^1.2.7. lerna WARN EHOIST_PKG_VERSION "@hyperledger/generator-caliper" package depends on js-yaml@^3.12.0, which differs from the hoisted js-yaml@^3.13.1. lerna info Installing external dependencies lerna info hoist Installing hoisted dependencies into root lerna info hoist Pruning hoisted dependencies lerna info hoist Finished pruning hoisted dependencies lerna info hoist Finished bootstrapping root lerna info Symlinking packages and binaries lerna success Bootstrapped 13 packages ---- Running Integration test for adaptor besu set -o pipefail

Barf if we don't recognize this test adaptor.

if [[ "${BENCHMARK}" = "" ]]; then echo You must set BENCHMARK to one of the desired test adaptors 'besu|ethereum|fabric|fisco-bcos|generator|sawtooth|iroha' echo For example: echo export BENCHMARK=fabric exit 1 fi

TEST_DIR="${BENCHMARK}_tests" if [[ -d "${TEST_DIR}" ]]; then "${TEST_DIR}"/run.sh else echo "Unknown target benchmark ${BENCHMARK}" exit 1 fi

Grab the parent directory.

DIR="$( cd "$( dirname "${BASH_SOURCE[0]}" )" && pwd )" cd "${DIR}"

bind during CI tests, using the package dir as CWD

Note: do not use env variables for binding settings, as subsequent launch calls will pick them up and bind again

if [[ "${BIND_IN_PACKAGE_DIR}" = "true" ]]; then ${CALL_METHOD} bind --caliper-bind-sut besu:latest --caliper-bind-cwd ./../../caliper-ethereum/ --caliper-bind-args="--no-save" fi 2020.05.13-20:04:38.780 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:04:38.949 +0300 info [bind] User-provided arguments for "npm install": "--no-save" 2020.05.13-20:04:38.950 +0300 info [bind] Binding for besu@latest. This might take some time... 2020.05.13-20:04:38.950 +0300 info [bind] Binding working directory: /home/cezar/caliper/packages/caliper-ethereum 2020.05.13-20:04:38.950 +0300 info [bind] Calling npm with: install --no-save web3@1.2.0 npm WARN deprecated mkdirp-promise@5.0.1: This package is broken and no longer maintained. 'mkdirp' itself supports promises now, please switch to that. npm WARN deprecated request@2.88.2: request has been deprecated, see https://github.com/request/request/issues/3142

web3-providers-ws@1.2.0 preinstall /home/cezar/caliper/packages/caliper-ethereum/node_modules/web3-providers-ws rm -rf node_modules/websocket/.git

scrypt@6.0.3 preinstall /home/cezar/caliper/packages/caliper-ethereum/node_modules/scrypt node node-scrypt-preinstall.js

scrypt@6.0.3 install /home/cezar/caliper/packages/caliper-ethereum/node_modules/scrypt node-gyp rebuild

make: Entering directory '/home/cezar/caliper/packages/caliper-ethereum/node_modules/scrypt/build' SOLINK_MODULE(target) Release/obj.target/copied_files.node COPY Release/copied_files.node CC(target) Release/obj.target/scrypt_wrapper/src/util/memlimit.o CC(target) Release/obj.target/scrypt_wrapper/src/scryptwrapper/keyderivation.o CC(target) Release/obj.target/scrypt_wrapper/src/scryptwrapper/pickparams.o CC(target) Release/obj.target/scrypt_wrapper/src/scryptwrapper/hash.o AR(target) Release/obj.target/scrypt_wrapper.a COPY Release/scrypt_wrapper.a CC(target) Release/obj.target/scrypt_lib/scrypt/scrypt-1.2.0/lib/crypto/crypto_scrypt.o CC(target) Release/obj.target/scrypt_lib/scrypt/scrypt-1.2.0/lib/crypto/crypto_scrypt_smix.o CC(target) Release/obj.target/scrypt_lib/scrypt/scrypt-1.2.0/libcperciva/util/warnp.o CC(target) Release/obj.target/scrypt_lib/scrypt/scrypt-1.2.0/libcperciva/alg/sha256.o CC(target) Release/obj.target/scrypt_lib/scrypt/scrypt-1.2.0/libcperciva/util/insecure_memzero.o CC(target) Release/obj.target/scrypt_lib/scrypt/scrypt-1.2.0/lib/scryptenc/scryptenc_cpuperf.o AR(target) Release/obj.target/scrypt_lib.a COPY Release/scrypt_lib.a CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_common.o CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_params_async.o In file included from ../src/node-boilerplate/inc/scrypt_params_async.h:28:0, from ../src/node-boilerplate/scrypt_params_async.cc:4: ../src/node-boilerplate/inc/scrypt_async.h: In member function ‘virtual void ScryptAsyncWorker::HandleErrorCallback()’: ../src/node-boilerplate/inc/scrypt_async.h:53:29: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(1, argv); ^ In file included from ../src/node-boilerplate/scrypt_params_async.cc:1:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ ../src/node-boilerplate/scrypt_params_async.cc: In member function ‘virtual void ScryptParamsAsyncWorker::HandleOKCallback()’: ../src/node-boilerplate/scrypt_params_async.cc:32:25: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(2, argv); ^ In file included from ../src/node-boilerplate/scrypt_params_async.cc:1:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_params_sync.o CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_kdf_async.o In file included from ../src/node-boilerplate/inc/scrypt_kdf_async.h:28:0, from ../src/node-boilerplate/scrypt_kdf_async.cc:4: ../src/node-boilerplate/inc/scrypt_async.h: In member function ‘virtual void ScryptAsyncWorker::HandleErrorCallback()’: ../src/node-boilerplate/inc/scrypt_async.h:53:29: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(1, argv); ^ In file included from ../src/node-boilerplate/scrypt_kdf_async.cc:1:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ ../src/node-boilerplate/scrypt_kdf_async.cc: In member function ‘virtual void ScryptKDFAsyncWorker::HandleOKCallback()’: ../src/node-boilerplate/scrypt_kdf_async.cc:28:27: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(2, argv); ^ In file included from ../src/node-boilerplate/scrypt_kdf_async.cc:1:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_kdf_sync.o CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_kdf-verify_sync.o CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_kdf-verify_async.o In file included from ../src/node-boilerplate/inc/scrypt_kdf-verify_async.h:28:0, from ../src/node-boilerplate/scrypt_kdf-verify_async.cc:4: ../src/node-boilerplate/inc/scrypt_async.h: In member function ‘virtual void ScryptAsyncWorker::HandleErrorCallback()’: ../src/node-boilerplate/inc/scrypt_async.h:53:29: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(1, argv); ^ In file included from ../src/node-boilerplate/scrypt_kdf-verify_async.cc:1:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ ../src/node-boilerplate/scrypt_kdf-verify_async.cc: In member function ‘virtual void ScryptKDFVerifyAsyncWorker::HandleOKCallback()’: ../src/node-boilerplate/scrypt_kdf-verify_async.cc:30:25: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(2, argv); ^ In file included from ../src/node-boilerplate/scrypt_kdf-verify_async.cc:1:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_hash_sync.o CXX(target) Release/obj.target/scrypt/src/node-boilerplate/scrypt_hash_async.o In file included from ../src/node-boilerplate/inc/scrypt_hash_async.h:28:0, from ../src/node-boilerplate/scrypt_hash_async.cc:28: ../src/node-boilerplate/inc/scrypt_async.h: In member function ‘virtual void ScryptAsyncWorker::HandleErrorCallback()’: ../src/node-boilerplate/inc/scrypt_async.h:53:29: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(1, argv); ^ In file included from ../src/node-boilerplate/scrypt_hash_async.cc:25:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ ../src/node-boilerplate/scrypt_hash_async.cc: In member function ‘virtual void ScryptHashAsyncWorker::HandleOKCallback()’: ../src/node-boilerplate/scrypt_hash_async.cc:52:25: warning: ‘v8::Local Nan::Callback::Call(int, v8::Local) const’ is deprecated [-Wdeprecated-declarations] callback->Call(2, argv); ^ In file included from ../src/node-boilerplate/scrypt_hash_async.cc:25:0: ../../nan/nan.h:1742:3: note: declared here Call(int argc, v8::Local argv[]) const { ^~~~ CXX(target) Release/obj.target/scrypt/scrypt_node.o SOLINK_MODULE(target) Release/obj.target/scrypt.node COPY Release/scrypt.node make: Leaving directory '/home/cezar/caliper/packages/caliper-ethereum/node_modules/scrypt/build'

websocket@1.0.26 install /home/cezar/caliper/packages/caliper-ethereum/node_modules/websocket (node-gyp rebuild 2> builderror.log) || (exit 0)

make: Entering directory '/home/cezar/caliper/packages/caliper-ethereum/node_modules/websocket/build' CXX(target) Release/obj.target/bufferutil/src/bufferutil.o SOLINK_MODULE(target) Release/obj.target/bufferutil.node COPY Release/bufferutil.node CXX(target) Release/obj.target/validation/src/validation.o SOLINK_MODULE(target) Release/obj.target/validation.node COPY Release/validation.node make: Leaving directory '/home/cezar/caliper/packages/caliper-ethereum/node_modules/websocket/build' npm WARN notsup Unsupported engine for mkdirp@1.0.4: wanted: {"node":">=10"} (current: {"node":"9.6.0","npm":"6.14.5"}) npm WARN notsup Not compatible with your version of node/npm: mkdirp@1.0.4

2 packages are looking for funding run npm fund for details

found 0 vulnerabilities

change default settings (add config paths too)

export CALIPER_PROJECTCONFIG=../caliper.yaml

dispose () { ${CALL_METHOD} launch master --caliper-workspace phase1 --caliper-flow-only-end }

PHASE 1: just starting the network

${CALL_METHOD} launch master --caliper-workspace phase1 --caliper-flow-only-start 2020.05.13-20:05:04.621 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:04.771 +0300 info [cli-launch-master] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase1 2020.05.13-20:05:04.771 +0300 info [cli-launch-master] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase1/benchconfig.yaml 2020.05.13-20:05:04.772 +0300 info [cli-launch-master] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase1/networkconfig.json 2020.05.13-20:05:04.772 +0300 info [cli-launch-master] Set SUT type: ethereum 2020.05.13-20:05:05.029 +0300 info [caliper-engine] Starting benchmark flow 2020.05.13-20:05:05.049 +0300 info [caliper-utils] Executing command: cd phase1; docker-compose -f ../config/docker-compose.yml up -d && sleep 30 Creating network "config_default" with the default driver Creating besu_clique ... done 2020.05.13-20:05:36.271 +0300 info [caliper-engine] Executed start command in 31.222 seconds 2020.05.13-20:05:36.271 +0300 info [caliper-engine] Skipping initialization phase due to benchmark flow conditioning 2020.05.13-20:05:36.271 +0300 info [caliper-engine] Skipping install smart contract phase due to benchmark flow conditioning 2020.05.13-20:05:36.272 +0300 info [caliper-engine] Skipping benchmark test phase due to benchmark flow conditioning 2020.05.13-20:05:36.272 +0300 info [caliper-engine] Skipping end command due to benchmark flow conditioning 2020.05.13-20:05:36.272 +0300 info [cli-launch-master] Benchmark successfully finished rc=$? if [[ ${rc} != 0 ]]; then echo "Failed CI step 1"; dispose; exit ${rc}; fi

PHASE 2: single caliper client, estimate gas on open

${CALL_METHOD} launch master --caliper-workspace phase2 --caliper-flow-skip-start --caliper-flow-skip-end 2020.05.13-20:05:36.456 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:36.597 +0300 info [cli-launch-master] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase2 2020.05.13-20:05:36.597 +0300 info [cli-launch-master] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase2/benchconfig.yaml 2020.05.13-20:05:36.597 +0300 info [cli-launch-master] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase2/networkconfig.json 2020.05.13-20:05:36.597 +0300 info [cli-launch-master] Set SUT type: ethereum 2020.05.13-20:05:36.844 +0300 info [caliper-engine] Starting benchmark flow 2020.05.13-20:05:36.860 +0300 info [caliper-engine] Skipping start commands due to benchmark flow conditioning 2020.05.13-20:05:36.871 +0300 info [caliper-engine] Executed "init" step in 0.011 seconds 2020.05.13-20:05:36.872 +0300 info [ethereum.js] Creating contracts... 2020.05.13-20:05:38.082 +0300 info [ethereum.js] Deployed contract simple at 0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7 2020.05.13-20:05:38.082 +0300 info [caliper-engine] Executed "install" step in 1.21 seconds 2020.05.13-20:05:38.082 +0300 info [messenger.js] Creating messenger of type "process-master" 2020.05.13-20:05:38.083 +0300 info [monitor.js] No monitor specified, will default to "none" 2020.05.13-20:05:38.084 +0300 debug [testObserver.js] Creating test observer of type "local" 2020.05.13-20:05:38.085 +0300 info [local-observer] Observer interval set to 1 seconds 2020.05.13-20:05:38.088 +0300 info [round-orchestrator] Monitors successfully started 2020.05.13-20:05:38.088 +0300 info [round-orchestrator] Started round 1 (open) 2020.05.13-20:05:38.089 +0300 info [worker-orchestrator] Launching worker 1 of 1 2020.05.13-20:05:38.094 +0300 info [worker-orchestrator] Messenger not configured, entering configure phase... 2020.05.13-20:05:38.095 +0300 info [worker-orchestrator] No existing workers detected, entering worker launch phase... 2020.05.13-20:05:38.095 +0300 info [worker-orchestrator] Waiting for 1 workers to be connected... 2020.05.13-20:05:38.265 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:38.398 +0300 info [cli-launch-worker] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase2/phase2 2020.05.13-20:05:38.398 +0300 info [cli-launch-worker] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase2/benchconfig.yaml 2020.05.13-20:05:38.398 +0300 info [cli-launch-worker] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase2/networkconfig.json 2020.05.13-20:05:38.398 +0300 info [cli-launch-worker] Set SUT type: ethereum 2020.05.13-20:05:38.634 +0300 info [messenger.js] Creating messenger of type "process-worker" for SUT ethereum 2020.05.13-20:05:43.100 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15766","timestamp":"2020-05-13T17:05:43.100Z","data":{"type":"register"}} 2020.05.13-20:05:43.101 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15766","timestamp":"2020-05-13T17:05:43.100Z","data":{"type":"register"}} 2020.05.13-20:05:43.101 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.101Z","data":{"type":"connected"}} 2020.05.13-20:05:43.102 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.101Z","data":{"type":"connected"}} 2020.05.13-20:05:43.102 +0300 debug [worker-orchestrator] Dealing with connected message {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.101Z","data":{"type":"connected"}} 2020.05.13-20:05:43.102 +0300 debug [worker-orchestrator] Handling connected message from 15816 with data {"type":"connected"} 2020.05.13-20:05:43.103 +0300 info [worker-orchestrator] 1 workers connected, progressing to worker assignment phase. 2020.05.13-20:05:43.103 +0300 info [worker-orchestrator] Workers currently unassigned, awaiting index assignment... 2020.05.13-20:05:43.103 +0300 debug [process-master-messenger] Sent message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.103Z","data":{"workerId":0,"type":"assignId"}} 2020.05.13-20:05:43.103 +0300 info [worker-orchestrator] Waiting for 1 workers to be assigned... 2020.05.13-20:05:43.103 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.103Z","data":{"workerId":0,"type":"assignId"}} 2020.05.13-20:05:43.103 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.103Z","data":{"type":"assigned"}} 2020.05.13-20:05:43.103 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.103Z","data":{"type":"assigned"}} 2020.05.13-20:05:43.104 +0300 debug [worker-orchestrator] Dealing with assigned message {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.103Z","data":{"type":"assigned"}} 2020.05.13-20:05:43.104 +0300 debug [worker-orchestrator] Handling assigned message from 15816 with data {"type":"assigned"} 2020.05.13-20:05:43.104 +0300 debug [worker-orchestrator] All workers completed phase assigned 2020.05.13-20:05:43.104 +0300 info [worker-orchestrator] 1 workers assigned, progressing to worker initialization phase. 2020.05.13-20:05:43.104 +0300 info [worker-orchestrator] Waiting for 1 workers to be ready... 2020.05.13-20:05:43.104 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15766","timestamp":"2020-05-13T17:05:43.104Z","data":{"type":"initialize"}} 2020.05.13-20:05:43.104 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15766","timestamp":"2020-05-13T17:05:43.104Z","data":{"type":"initialize"}} 2020.05.13-20:05:43.104 +0300 info [message-handler] Handling "init" message 2020.05.13-20:05:43.104 +0300 debug [message-handler] Message content ({"type":"initialize"}) 2020.05.13-20:05:43.112 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.112Z","data":{"type":"ready"}} 2020.05.13-20:05:43.112 +0300 info [message-handler] Handled successful "init" message for worker 0 2020.05.13-20:05:43.112 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.112Z","data":{"type":"ready"}} 2020.05.13-20:05:43.112 +0300 debug [worker-orchestrator] Dealing with ready message {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.112Z","data":{"type":"ready"}} 2020.05.13-20:05:43.112 +0300 debug [worker-orchestrator] Handling ready message from 15816 with data {"type":"ready"} 2020.05.13-20:05:43.112 +0300 debug [worker-orchestrator] All workers completed phase ready 2020.05.13-20:05:43.112 +0300 info [worker-orchestrator] 1 workers ready, progressing to test preparation phase. 2020.05.13-20:05:43.113 +0300 debug [process-master-messenger] Sent message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.113Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}} 2020.05.13-20:05:43.113 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.113Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}} 2020.05.13-20:05:43.113 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:43.113 +0300 debug [message-handler] Message content ({"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}) 2020.05.13-20:05:43.114 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"})

2020.05.13-20:05:43.152 +0300 info [caliper-local-client.js] Client[0] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:43.152 +0300 info [caliper-local-client.js] Info: client 0 prepare test :opening accountsphase complete

2020.05.13-20:05:43.153 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.152Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.153 +0300 debug [worker-orchestrator] Dealing with prepared message {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.152Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.153 +0300 debug [worker-orchestrator] Handling prepared message from 15816 with data {"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}

2020.05.13-20:05:43.153 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.152Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.153 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 0 and test round 0 with error TypeError: this.web3.eth.getChainId is not a function at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35) at at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:43.154 +0300 error [round-orchestrator] Failed round 1 (open): Error: TypeError: this.web3.eth.getChainId is not a function

at WorkerOrchestrator.updateWorkerPhase (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:211:61)
at WorkerOrchestrator.processWorkerUpdate (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:124:18)
at ChildProcess.<anonymous> (/home/cezar/caliper/packages/caliper-core/lib/common/messaging/process-master.js:51:30)
at ChildProcess.emit (events.js:125:13)
at emit (internal/child_process.js:780:12)
at process._tickCallback (internal/process/next_tick.js:152:19)

2020.05.13-20:05:43.154 +0300 info [round-orchestrator] Started round 2 (query) 2020.05.13-20:05:43.154 +0300 info [worker-orchestrator] Existing 1 connected workers detected, progressing to worker assignment phase. 2020.05.13-20:05:43.154 +0300 info [worker-orchestrator] Existing 1 connected workers detected are assigned, progressing to worker initialization phase. 2020.05.13-20:05:43.154 +0300 info [worker-orchestrator] Existing 1 prepared workers detected, progressing to test preparation phase. 2020.05.13-20:05:43.155 +0300 debug [process-master-messenger] Sent message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.155Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}} 2020.05.13-20:05:43.155 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.155Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}} 2020.05.13-20:05:43.155 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:43.156 +0300 debug [message-handler] Message content ({"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}) 2020.05.13-20:05:43.156 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"})

2020.05.13-20:05:43.160 +0300 info [caliper-local-client.js] Client[0] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:43.160 +0300 info [caliper-local-client.js] Info: client 0 prepare test :querying accountsphase complete

2020.05.13-20:05:43.161 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.160Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.161 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 0 and test round 1 with error TypeError: this.web3.eth.getChainId is not a function at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35) at at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:43.161 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.160Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.161 +0300 debug [worker-orchestrator] Dealing with prepared message {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.160Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.161 +0300 debug [worker-orchestrator] Handling prepared message from 15816 with data {"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}

2020.05.13-20:05:43.161 +0300 error [round-orchestrator] Failed round 2 (query): Error: TypeError: this.web3.eth.getChainId is not a function at WorkerOrchestrator.updateWorkerPhase (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:211:61) at WorkerOrchestrator.processWorkerUpdate (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:124:18) at ChildProcess. (/home/cezar/caliper/packages/caliper-core/lib/common/messaging/process-master.js:51:30) at ChildProcess.emit (events.js:125:13) at emit (internal/child_process.js:780:12) at process._tickCallback (internal/process/next_tick.js:152:19) 2020.05.13-20:05:43.162 +0300 info [round-orchestrator] Started round 3 (transfer) 2020.05.13-20:05:43.162 +0300 info [worker-orchestrator] Existing 1 connected workers detected, progressing to worker assignment phase. 2020.05.13-20:05:43.162 +0300 info [worker-orchestrator] Existing 1 connected workers detected are assigned, progressing to worker initialization phase. 2020.05.13-20:05:43.163 +0300 info [worker-orchestrator] Existing 1 prepared workers detected, progressing to test preparation phase. 2020.05.13-20:05:43.163 +0300 debug [process-master-messenger] Sent message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.163Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}} 2020.05.13-20:05:43.164 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15816"],"from":"15766","timestamp":"2020-05-13T17:05:43.163Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}} 2020.05.13-20:05:43.164 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:43.164 +0300 debug [message-handler] Message content ({"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"}) 2020.05.13-20:05:43.164 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","estimateGas":true,"gas":{"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x588108d3eAB34e94484d7cdA5a1D31804cA96FE7"}}},"totalClients":1,"type":"prepare"})

2020.05.13-20:05:43.170 +0300 info [caliper-local-client.js] Client[0] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:43.170 +0300 info [caliper-local-client.js] Info: client 0 prepare test :transfering moneyphase complete

2020.05.13-20:05:43.170 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.170Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.171 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 0 and test round 2 with error TypeError: this.web3.eth.getChainId is not a function at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35) at at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:43.171 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.170Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.172 +0300 debug [worker-orchestrator] Dealing with prepared message {"to":["orchestrator"],"from":"15816","timestamp":"2020-05-13T17:05:43.170Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:43.172 +0300 debug [worker-orchestrator] Handling prepared message from 15816 with data {"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}

2020.05.13-20:05:43.173 +0300 error [round-orchestrator] Failed round 3 (transfer): Error: TypeError: this.web3.eth.getChainId is not a function

at WorkerOrchestrator.updateWorkerPhase (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:211:61)
at WorkerOrchestrator.processWorkerUpdate (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:124:18)
at ChildProcess.<anonymous> (/home/cezar/caliper/packages/caliper-core/lib/common/messaging/process-master.js:51:30)
at ChildProcess.emit (events.js:125:13)
at emit (internal/child_process.js:780:12)
at process._tickCallback (internal/process/next_tick.js:152:19)

2020.05.13-20:05:43.173 +0300 info [report-builder] ### All test results ### 2020.05.13-20:05:43.173 +0300 error [report-builder] No data within test result; implies error within configuration files 2020.05.13-20:05:43.174 +0300 error [round-orchestrator] Error while finalizing the report: Error: unrecognized report table at ReportBuilder.setSummaryTable (/home/cezar/caliper/packages/caliper-core/lib/master/report/report-builder.js:142:19) at Report.printResultsByRound (/home/cezar/caliper/packages/caliper-core/lib/master/report/report.js:254:28) at RoundOrchestrator.run (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/round-orchestrator.js:221:25) at at process._tickCallback (internal/process/next_tick.js:160:7) 2020.05.13-20:05:43.174 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15766","timestamp":"2020-05-13T17:05:43.174Z","data":{"type":"exit"}} 2020.05.13-20:05:43.174 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15766","timestamp":"2020-05-13T17:05:43.174Z","data":{"type":"exit"}} 2020.05.13-20:05:43.174 +0300 info [message-handler] Handling "exit" message 2020.05.13-20:05:43.175 +0300 info [round-orchestrator] Benchmark finished in 5.087 seconds. Total rounds: 3. Successful rounds: 0. Failed rounds: 3. 2020.05.13-20:05:43.175 +0300 info [caliper-engine] Skipping end command due to benchmark flow conditioning 2020.05.13-20:05:43.175 +0300 info [cli-launch-master] Benchmark successfully finished rc=$? if [[ ${rc} != 0 ]]; then echo "Failed CI step 2"; dispose; exit ${rc}; fi

PHASE 3: multiple caliper clients, all gas fixed

${CALL_METHOD} launch master --caliper-workspace phase3 --caliper-flow-skip-start --caliper-flow-skip-end 2020.05.13-20:05:43.376 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:43.522 +0300 info [cli-launch-master] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3 2020.05.13-20:05:43.523 +0300 info [cli-launch-master] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/benchconfig.yaml 2020.05.13-20:05:43.523 +0300 info [cli-launch-master] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/networkconfig.json 2020.05.13-20:05:43.523 +0300 info [cli-launch-master] Set SUT type: ethereum 2020.05.13-20:05:43.787 +0300 info [caliper-engine] Starting benchmark flow 2020.05.13-20:05:43.802 +0300 info [caliper-engine] Skipping start commands due to benchmark flow conditioning 2020.05.13-20:05:43.815 +0300 info [caliper-engine] Executed "init" step in 0.012 seconds 2020.05.13-20:05:43.815 +0300 info [ethereum.js] Creating contracts... 2020.05.13-20:05:48.027 +0300 info [ethereum.js] Deployed contract simple at 0x7F9FbeB60b3c7053494Db303740AA57CCbF06535 2020.05.13-20:05:48.027 +0300 info [caliper-engine] Executed "install" step in 4.212 seconds 2020.05.13-20:05:48.027 +0300 info [messenger.js] Creating messenger of type "process-master" 2020.05.13-20:05:48.028 +0300 info [monitor.js] No monitor specified, will default to "none" 2020.05.13-20:05:48.029 +0300 debug [testObserver.js] Creating test observer of type "local" 2020.05.13-20:05:48.029 +0300 info [local-observer] Observer interval set to 1 seconds 2020.05.13-20:05:48.032 +0300 info [round-orchestrator] Monitors successfully started 2020.05.13-20:05:48.032 +0300 info [round-orchestrator] Started round 1 (open) 2020.05.13-20:05:48.033 +0300 info [worker-orchestrator] Launching worker 1 of 2 2020.05.13-20:05:48.041 +0300 info [worker-orchestrator] Launching worker 2 of 2 2020.05.13-20:05:48.046 +0300 info [worker-orchestrator] Messenger not configured, entering configure phase... 2020.05.13-20:05:48.046 +0300 info [worker-orchestrator] No existing workers detected, entering worker launch phase... 2020.05.13-20:05:48.046 +0300 info [worker-orchestrator] Waiting for 2 workers to be connected... 2020.05.13-20:05:48.215 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:48.229 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/phase3 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/phase3 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/benchconfig.yaml 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/benchconfig.yaml 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/networkconfig.json 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase3/networkconfig.json 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set SUT type: ethereum 2020.05.13-20:05:48.372 +0300 info [cli-launch-worker] Set SUT type: ethereum 2020.05.13-20:05:48.631 +0300 info [messenger.js] Creating messenger of type "process-worker" for SUT ethereum 2020.05.13-20:05:48.636 +0300 info [messenger.js] Creating messenger of type "process-worker" for SUT ethereum 2020.05.13-20:05:53.047 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.046Z","data":{"type":"register"}} 2020.05.13-20:05:53.047 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.046Z","data":{"type":"register"}} 2020.05.13-20:05:53.048 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.046Z","data":{"type":"register"}} 2020.05.13-20:05:53.048 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.046Z","data":{"type":"register"}} 2020.05.13-20:05:53.049 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.048Z","data":{"type":"connected"}} 2020.05.13-20:05:53.049 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.049Z","data":{"type":"connected"}} 2020.05.13-20:05:53.050 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.048Z","data":{"type":"connected"}} 2020.05.13-20:05:53.050 +0300 debug [worker-orchestrator] Dealing with connected message {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.048Z","data":{"type":"connected"}} 2020.05.13-20:05:53.050 +0300 debug [worker-orchestrator] Handling connected message from 15894 with data {"type":"connected"} 2020.05.13-20:05:53.050 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.049Z","data":{"type":"connected"}} 2020.05.13-20:05:53.050 +0300 debug [worker-orchestrator] Dealing with connected message {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.049Z","data":{"type":"connected"}} 2020.05.13-20:05:53.051 +0300 debug [worker-orchestrator] Handling connected message from 15888 with data {"type":"connected"} 2020.05.13-20:05:53.051 +0300 info [worker-orchestrator] 2 workers connected, progressing to worker assignment phase. 2020.05.13-20:05:53.051 +0300 info [worker-orchestrator] Workers currently unassigned, awaiting index assignment... 2020.05.13-20:05:53.051 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":0,"type":"assignId"}} 2020.05.13-20:05:53.051 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":0,"type":"assignId"}} 2020.05.13-20:05:53.051 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":0,"type":"assignId"}} 2020.05.13-20:05:53.051 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":1,"type":"assignId"}} 2020.05.13-20:05:53.051 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":1,"type":"assignId"}} 2020.05.13-20:05:53.051 +0300 info [worker-orchestrator] Waiting for 2 workers to be assigned... 2020.05.13-20:05:53.051 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.051Z","data":{"type":"assigned"}} 2020.05.13-20:05:53.051 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":1,"type":"assignId"}} 2020.05.13-20:05:53.051 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.051Z","data":{"type":"assigned"}} 2020.05.13-20:05:53.051 +0300 debug [worker-orchestrator] Dealing with assigned message {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.051Z","data":{"type":"assigned"}} 2020.05.13-20:05:53.052 +0300 debug [worker-orchestrator] Handling assigned message from 15888 with data {"type":"assigned"} 2020.05.13-20:05:53.052 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":0,"type":"assignId"}} 2020.05.13-20:05:53.052 +0300 debug [worker-orchestrator] Pending ready messages from workers: [15894] 2020.05.13-20:05:53.052 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.051Z","data":{"workerId":1,"type":"assignId"}} 2020.05.13-20:05:53.052 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.052Z","data":{"type":"assigned"}} 2020.05.13-20:05:53.052 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.052Z","data":{"type":"assigned"}} 2020.05.13-20:05:53.052 +0300 debug [worker-orchestrator] Dealing with assigned message {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.052Z","data":{"type":"assigned"}} 2020.05.13-20:05:53.052 +0300 debug [worker-orchestrator] Handling assigned message from 15894 with data {"type":"assigned"} 2020.05.13-20:05:53.052 +0300 debug [worker-orchestrator] All workers completed phase assigned 2020.05.13-20:05:53.052 +0300 info [worker-orchestrator] 2 workers assigned, progressing to worker initialization phase. 2020.05.13-20:05:53.052 +0300 info [worker-orchestrator] Waiting for 2 workers to be ready... 2020.05.13-20:05:53.053 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.053Z","data":{"type":"initialize"}} 2020.05.13-20:05:53.053 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.053Z","data":{"type":"initialize"}} 2020.05.13-20:05:53.053 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.053Z","data":{"type":"initialize"}} 2020.05.13-20:05:53.053 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.053Z","data":{"type":"initialize"}} 2020.05.13-20:05:53.053 +0300 info [message-handler] Handling "init" message 2020.05.13-20:05:53.053 +0300 debug [message-handler] Message content ({"type":"initialize"}) 2020.05.13-20:05:53.053 +0300 info [message-handler] Handling "init" message 2020.05.13-20:05:53.053 +0300 debug [message-handler] Message content ({"type":"initialize"}) 2020.05.13-20:05:53.061 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.061Z","data":{"type":"ready"}} 2020.05.13-20:05:53.061 +0300 info [message-handler] Handled successful "init" message for worker 0 2020.05.13-20:05:53.061 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.061Z","data":{"type":"ready"}} 2020.05.13-20:05:53.061 +0300 debug [worker-orchestrator] Dealing with ready message {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.061Z","data":{"type":"ready"}} 2020.05.13-20:05:53.062 +0300 debug [worker-orchestrator] Handling ready message from 15888 with data {"type":"ready"} 2020.05.13-20:05:53.062 +0300 debug [worker-orchestrator] Pending ready messages from workers: [15894] 2020.05.13-20:05:53.071 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.071Z","data":{"type":"ready"}} 2020.05.13-20:05:53.072 +0300 debug [worker-orchestrator] Dealing with ready message {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.071Z","data":{"type":"ready"}} 2020.05.13-20:05:53.072 +0300 debug [worker-orchestrator] Handling ready message from 15894 with data {"type":"ready"} 2020.05.13-20:05:53.072 +0300 debug [worker-orchestrator] All workers completed phase ready 2020.05.13-20:05:53.072 +0300 info [worker-orchestrator] 2 workers ready, progressing to test preparation phase. 2020.05.13-20:05:53.072 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.072 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.072 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.072 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.072 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.072 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:53.073 +0300 debug [message-handler] Message content ({"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.073 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.071Z","data":{"type":"ready"}} 2020.05.13-20:05:53.073 +0300 info [message-handler] Handled successful "init" message for worker 1 2020.05.13-20:05:53.073 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.075 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.075 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.075 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:53.075 +0300 debug [message-handler] Message content ({"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.075 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.076 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.072Z","data":{"label":"open","rateControl":{"type":"fixed-rate","opts":{"tps":10}},"trim":0,"args":{"money":10000},"cb":"../open.js","testRound":0,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}}

2020.05.13-20:05:53.087 +0300 info [caliper-local-client.js] Client[0] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.088 +0300 info [caliper-local-client.js] Info: client 0 prepare test :opening accountsphase complete

2020.05.13-20:05:53.089 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.089Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.089 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.089Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.089 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 0 and test round 0 with error TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.089 +0300 debug [worker-orchestrator] Dealing with prepared message {"to":["orchestrator"],"from":"15888","timestamp":"2020-05-13T17:05:53.089Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.091 +0300 debug [worker-orchestrator] Handling prepared message from 15888 with data {"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}

2020.05.13-20:05:53.093 +0300 error [round-orchestrator] Failed round 1 (open): Error: TypeError: this.web3.eth.getChainId is not a function

at WorkerOrchestrator.updateWorkerPhase (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:211:61)
at WorkerOrchestrator.processWorkerUpdate (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:124:18)
at ChildProcess.<anonymous> (/home/cezar/caliper/packages/caliper-core/lib/common/messaging/process-master.js:51:30)
at ChildProcess.emit (events.js:125:13)
at emit (internal/child_process.js:780:12)
at process._tickCallback (internal/process/next_tick.js:152:19)

2020.05.13-20:05:53.093 +0300 info [round-orchestrator] Started round 2 (query) 2020.05.13-20:05:53.093 +0300 info [worker-orchestrator] Existing 2 connected workers detected, progressing to worker assignment phase. 2020.05.13-20:05:53.093 +0300 info [worker-orchestrator] Existing 2 connected workers detected are assigned, progressing to worker initialization phase. 2020.05.13-20:05:53.093 +0300 info [worker-orchestrator] Existing 2 prepared workers detected, progressing to test preparation phase. 2020.05.13-20:05:53.093 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.093 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.093 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.094 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}}

2020.05.13-20:05:53.095 +0300 info [caliper-local-client.js] Client[1] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.096 +0300 info [caliper-local-client.js] Info: client 1 prepare test :opening accountsphase complete

2020.05.13-20:05:53.096 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.096Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.096 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 1 and test round 0 with error TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.096 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.096Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.096 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.095 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}}

2020.05.13-20:05:53.096 +0300 debug [worker-orchestrator] Dealing with prepared message {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.096Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.096 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}}

2020.05.13-20:05:53.096 +0300 debug [worker-orchestrator] Handling prepared message from 15894 with data {"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}

2020.05.13-20:05:53.096 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:53.096 +0300 debug [message-handler] Message content ({"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"})

2020.05.13-20:05:53.097 +0300 error [round-orchestrator] Failed round 2 (query): Error: TypeError: this.web3.eth.getChainId is not a function

at WorkerOrchestrator.updateWorkerPhase (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:211:61)
at WorkerOrchestrator.processWorkerUpdate (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:124:18)
at ChildProcess.<anonymous> (/home/cezar/caliper/packages/caliper-core/lib/common/messaging/process-master.js:51:30)
at ChildProcess.emit (events.js:125:13)
at emit (internal/child_process.js:780:12)
at process._tickCallback (internal/process/next_tick.js:152:19)

2020.05.13-20:05:53.097 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.097 +0300 info [round-orchestrator] Started round 3 (transfer) 2020.05.13-20:05:53.097 +0300 info [worker-orchestrator] Existing 2 connected workers detected, progressing to worker assignment phase. 2020.05.13-20:05:53.097 +0300 info [worker-orchestrator] Existing 2 connected workers detected are assigned, progressing to worker initialization phase. 2020.05.13-20:05:53.097 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:53.097 +0300 info [worker-orchestrator] Existing 2 prepared workers detected, progressing to test preparation phase. 2020.05.13-20:05:53.097 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.097 +0300 debug [process-master-messenger] Sent message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.097 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.097 +0300 debug [message-handler] Message content ({"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.097 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.098 +0300 debug [process-master-messenger] Sent message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.098 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.098 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:53.099 +0300 debug [message-handler] Message content ({"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.099 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.099 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15894"],"from":"15853","timestamp":"2020-05-13T17:05:53.093Z","data":{"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.099 +0300 debug [caliper-local-client.js] prepareTest() with: ({"label":"query","rateControl":{"type":"fixed-feedback-rate","opts":{"tps":10,"unfinished_per_client":5}},"trim":0,"cb":"../query.js","testRound":1,"pushUrl":null,"numb":200,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"})

2020.05.13-20:05:53.100 +0300 info [caliper-local-client.js] Client[1] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.101 +0300 info [caliper-local-client.js] Info: client 1 prepare test :querying accountsphase complete

2020.05.13-20:05:53.101 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.101Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.101 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 1 and test round 1 with error TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.102 +0300 debug [process-master-messenger] Processing worker message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.101Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.102 +0300 debug [worker-orchestrator] Dealing with prepared message {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.101Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.102 +0300 info [caliper-local-client.js] Client[1] encountered an error during prepare test phase: TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.102 +0300 debug [worker-orchestrator] Handling prepared message from 15894 with data {"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}

2020.05.13-20:05:53.102 +0300 info [caliper-local-client.js] Info: client 1 prepare test :transfering moneyphase complete

2020.05.13-20:05:53.102 +0300 error [round-orchestrator] Failed round 3 (transfer): Error: TypeError: this.web3.eth.getChainId is not a function

at WorkerOrchestrator.updateWorkerPhase (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:211:61)
at WorkerOrchestrator.processWorkerUpdate (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/worker-orchestrator.js:124:18)
at ChildProcess.<anonymous> (/home/cezar/caliper/packages/caliper-core/lib/common/messaging/process-master.js:51:30)
at ChildProcess.emit (events.js:125:13)
at emit (internal/child_process.js:780:12)
at process._tickCallback (internal/process/next_tick.js:152:19)

2020.05.13-20:05:53.102 +0300 debug [mqtt-worker-messenger] ethereum worker sent message: {"to":["orchestrator"],"from":"15894","timestamp":"2020-05-13T17:05:53.102Z","data":{"error":"TypeError: this.web3.eth.getChainId is not a function","type":"prepared"}}

2020.05.13-20:05:53.102 +0300 error [message-handler] Handled unsuccessful "prepare" message for worker 1 and test round 2 with error TypeError: this.web3.eth.getChainId is not a function

at Ethereum.getContext (/home/cezar/caliper/packages/caliper-ethereum/lib/ethereum.js:147:35)
at <anonymous>
at process._tickCallback (internal/process/next_tick.js:160:7)

2020.05.13-20:05:53.102 +0300 info [report-builder] ### All test results ### 2020.05.13-20:05:53.103 +0300 error [report-builder] No data within test result; implies error within configuration files 2020.05.13-20:05:53.103 +0300 debug [mqtt-worker-messenger] ethereum worker processing orchestrator message: {"to":["15888"],"from":"15853","timestamp":"2020-05-13T17:05:53.097Z","data":{"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}} 2020.05.13-20:05:53.104 +0300 error [round-orchestrator] Error while finalizing the report: Error: unrecognized report table at ReportBuilder.setSummaryTable (/home/cezar/caliper/packages/caliper-core/lib/master/report/report-builder.js:142:19) at Report.printResultsByRound (/home/cezar/caliper/packages/caliper-core/lib/master/report/report.js:254:28) at RoundOrchestrator.run (/home/cezar/caliper/packages/caliper-core/lib/master/orchestrators/round-orchestrator.js:221:25) at at process._tickCallback (internal/process/next_tick.js:160:7) 2020.05.13-20:05:53.105 +0300 info [message-handler] Handling "prepare" message 2020.05.13-20:05:53.105 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.105Z","data":{"type":"exit"}} 2020.05.13-20:05:53.105 +0300 debug [process-master-messenger] Sent message: {"to":["all"],"from":"15853","timestamp":"2020-05-13T17:05:53.105Z","data":{"type":"exit"}} 2020.05.13-20:05:53.105 +0300 debug [message-handler] Message content ({"label":"transfer","rateControl":{"type":"linear-rate","opts":{"startingTps":5,"finishingTps":20}},"trim":0,"args":{"money":10000},"cb":"../transfer.js","testRound":2,"pushUrl":null,"numb":100,"clientArgs":{"contracts":{"simple":{"path":"../src/simple/simple.json","gas":{"open":45000,"query":100000,"transfer":70000},"abi":[{"constant":false,"inputs":[{"internalType":"string","name":"acc_from","type":"string"},{"internalType":"string","name":"acc_to","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"transfer","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x1de45b10"},{"constant":true,"inputs":[{"internalType":"string","name":"acc_id","type":"string"}],"name":"query","outputs":[{"internalType":"int256","name":"amount","type":"int256"}],"payable":false,"stateMutability":"view","type":"function","signature":"0x7c261929"},{"constant":false,"inputs":[{"internalType":"string","name":"acc_id","type":"string"},{"internalType":"int256","name":"amount","type":"int256"}],"name":"open","outputs":[],"payable":false,"stateMutability":"nonpayable","type":"function","signature":"0x90641293"}],"address":"0x7F9FbeB60b3c7053494Db303740AA57CCbF06535"}}},"totalClients":2,"type":"prepare"}) 2020.05.13-20:05:53.106 +0300 info [round-orchestrator] Benchmark finished in 5.074 seconds. Total rounds: 3. Successful rounds: 0. Failed rounds: 3. 2020.05.13-20:05:53.106 +0300 info [caliper-engine] Skipping end command due to benchmark flow conditioning 2020.05.13-20:05:53.106 +0300 info [cli-launch-master] Benchmark successfully finished rc=$? if [[ ${rc} != 0 ]]; then echo "Failed CI step 3"; dispose; exit ${rc}; fi

PHASE 5: just disposing of the network

${CALL_METHOD} launch master --caliper-workspace phase1 --caliper-flow-only-end 2020.05.13-20:05:53.302 +0300 debug [blockchain] Constructed a winston logger with the specified settings 2020.05.13-20:05:53.441 +0300 info [cli-launch-master] Set workspace path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase1 2020.05.13-20:05:53.441 +0300 info [cli-launch-master] Set benchmark configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase1/benchconfig.yaml 2020.05.13-20:05:53.441 +0300 info [cli-launch-master] Set network configuration path: /home/cezar/caliper/packages/caliper-tests-integration/besu_tests/phase1/networkconfig.json 2020.05.13-20:05:53.441 +0300 info [cli-launch-master] Set SUT type: ethereum 2020.05.13-20:05:53.701 +0300 info [caliper-engine] Starting benchmark flow 2020.05.13-20:05:53.717 +0300 info [caliper-engine] Skipping start commands due to benchmark flow conditioning 2020.05.13-20:05:53.717 +0300 info [caliper-engine] Skipping initialization phase due to benchmark flow conditioning 2020.05.13-20:05:53.717 +0300 info [caliper-engine] Skipping install smart contract phase due to benchmark flow conditioning 2020.05.13-20:05:53.717 +0300 info [caliper-engine] Skipping benchmark test phase due to benchmark flow conditioning 2020.05.13-20:05:53.717 +0300 info [caliper-utils] Executing command: cd phase1; docker-compose -f ../config/docker-compose.yml down Stopping besu_clique ... done Removing besu_clique ... done Removing network config_default 2020.05.13-20:05:54.643 +0300 info [caliper-engine] Executed end command in 0.926 seconds 2020.05.13-20:05:54.643 +0300 info [cli-launch-master] Benchmark successfully finished rc=$? if [[ ${rc} != 0 ]]; then echo "Failed CI step 5"; exit ${rc}; fi

Possible Fix

fix master branch for besu caliper integration

Steps to Reproduce

  1. git clone https:..github.com/hyperledger/caliper
  2. cd caliper
  3. npm i
  4. npm fund
  5. npm run repoclean
  6. sudo chown -R 1000:1000 /home/(your username)/.npm
  7. npm i web3 (had some issue with node-gyp and other npm errors an)
  8. npm run bootstrap
  9. configure networkcofig.json and docker-compose.yml to allow ws connection on a random port
  10. BENCHMARK=besu ./.travis/benchmark-integration-test-direct.sh

Existing issues

Context

Your Environment

aklenik commented 4 years ago

@cezarvatafu The http->ws change left a bug in the docker-compose file (ws is not enabled), but other than that, I haven't run into your bootstrap (following this) or chainId issue. Also note, that Node.Js 9.x is not supported, please try using the 8.x or 10.x LTS versions