console icon indicating copy to clipboard operation
console copied to clipboard

Published 20 hours ago • verified publisher icon FISCO-BCOS

ubuntu 20.04 fisco-bcos 3.0-rc4 Max在使用tars基于host网络模式部署成功之后，tars控制台显示rpc,gateway,node服务均是active，但是v3.0.0-rc4版console在参照向导配置之后，启动报错。

Open mykidxiao opened this issue 2 years ago • 0 comments

以下是执行bash start.sh之后的日志输出，提示Get ca basic failed和The websocket connection handshake timeout，具体日志信息如下：

  1. cpp_sdk_log_2022072223.13.log

info|2022-07-22 23:13:49.804602|[SDK][WS][SERVICE],[NEWOBJ][WsService]=0x7f37a46c9c20 info|2022-07-22 23:13:49.804646|[RPCWS][SERVICE]init the local protocol,minVersion=1,maxVersion=1,module=2 info|2022-07-22 23:13:49.807798|[DEFAULT][WS][INITIALIZER][initWsService]start websocket service as client,connected size=1 info|2022-07-22 23:13:49.807831|[DEFAULT][WS][INITIALIZER][initWsService]initializer for websocket service,listenIP=,listenPort=0,disableSsl=false,server=false,client=true,threadPoolSize=4,maxMsgSize=33554432,msgTimeOut=10000,connected peers=1 info|2022-07-22 23:13:49.807869|[bcos_sdk_create][NEWOBJ],sdk=0x7f37a46c3da0 info|2022-07-22 23:13:50.008430|[DEFAULT][WS][CONNECTOR][connectToWsServer]async_connect success,endpoint=127.0.0.1:20200 info|2022-07-22 23:13:50.008698|[DEFAULT][WS][STREAM],[NEWOBJ][WsStream]=0x7f372c003f50 info|2022-07-22 23:13:50.013044|[DEFAULT][BOOSTSSL][NODEINFO][NEW]SSLContext pubHex: 3082010a0282010100b41cec3648b93cc7a67cbde69439076d1d947dea85169937c1ab23553de84e565bfbdaad764f22664950d19c65a2f1ddee17a62e6580cd83cde10641ee03734999a5271996cce3a5fc48e5529b71736cc38045f53decd18b4c5cda3dfaa406c33650bf17c1cb0d29e95227298b4d6aad6c0c787a261a8ba9ff20792c8154c64d6e76d882ecc879cf3fafb4ec86b06e7da17d5978cd231f93fae26006a85a8cf852fde90e874691d9cd3e8439b349aa0909f9233d5dbb1a2786d0d6d8375c1880e9aa1e8195bf721f1c9a3d1a2e409fe14b051e4dde6906a3ba819309fc99fe117eee5d59887d21942030d956db448f0824d266ee6466843824c9feb6b5285c1d0203010001 warning|2022-07-22 23:13:50.013131|[DEFAULT][BOOSTSSL][NODEINFO]Get ca basic failed info|2022-07-22 23:13:50.013604|[DEFAULT][BOOSTSSL][NODEINFO][NEW]SSLContext pubHex: 3082010a0282010100b753586713cba8114c17071cd3e9dadacbfec278cf27386cec0a3358641b99a255f6ab8fe4709cae373a2a0710214741728cfce4d06a81c6737354a56c4c1c0c610ed46858da56b0c15cf2677dbc32565a2142e1e4560e9a307051a1109af95b18ccecf5bcd7edcf4a1b0ba2ca75a0a9189c665b2bb86b044cd0eafbb91754dd785d70c0f4d7dc1b724abefdf0a6707711aaf6bfc55dcbbc604ed249968a6f1b3ad4ff884cd1be4af85b3ff965ed8e4c04be2ee87210681ba1652ec1ac1cbc73acc41b768c21c771a7a0ae322b19863ac714e44348e000aa13dfdeeed80e8793c9e0e46704c76b9a19b96873e2b86407311e2571282dfbebf06d34188dfa71510203010001 info|2022-07-22 23:13:50.022553|[DEFAULT][WS][CONNECTOR][connectToWsServer]ssl async_handshake success,host=127.0.0.1,port=20200 info|2022-07-22 23:13:50.023681|[DEFAULT][WS][CONNECTOR][connectToWsServer]websocket handshake successfully,host=127.0.0.1,port=20200 info|2022-07-22 23:13:50.023741|[SDK][WS][SESSION],[NEWOBJ][WSSESSION]=0x7f372c0269d0 info|2022-07-22 23:13:50.023827|[SDK][WS][SERVICE]connected nodes,count=0 info|2022-07-22 23:13:50.023850|[SDK][WS][SERVICE][newSession]start the session,endPoint=127.0.0.1:20200 info|2022-07-22 23:13:50.023872|[SDK][WS][SERVICE][addSession]add session to mapping,connectedEndPoint=127.0.0.1:20200,endPoint=127.0.0.1:20200,result=true info|2022-07-22 23:13:50.023880|[SDK][WS][SERVICE][start]start websocket service successfully,model=1,max msg size=33554432 info|2022-07-22 23:13:50.023900|[SDK][WS][SERVICE][onConnect],endpoint=127.0.0.1:20200,connectedEndPoint=127.0.0.1:20200,refCount=6 info|2022-07-22 23:13:50.024061|[RPCWS][SERVICE][startHandshake],endpoint=127.0.0.1:20200 info|2022-07-22 23:13:50.024590|[SDK][WS][SESSION][startAsClient]websocket handshake successfully,endPoint=127.0.0.1:20200,session=0x7f372c0269d0 warning|2022-07-22 23:14:00.024505|[SDK][WS][SESSION][onRespTimeout],seq=e38ed56b0b1642a9bc88ca6820c72dca info|2022-07-22 23:14:00.024579|[SDK][WS][SESSION]127.0.0.1:20200 queueSize: 0 warning|2022-07-22 23:14:00.024720|[RPCWS][SERVICE][startHandshake]callback response error,endpoint=127.0.0.1:20200,errorCode=-4008,errorMessage=waiting for message response timed out info|2022-07-22 23:14:00.024750|[SDK][WS][SESSION][drop],reason=4294963289,endpoint=127.0.0.1:20200,session=0x7f372c0269d0 info|2022-07-22 23:14:00.024760|[SDK][WS][SESSION][drop],reason=4294963289,endpoint=127.0.0.1:20200,cb size=0,session=0x7f372c0269d0 info|2022-07-22 23:14:00.024915|[DEFAULT][WS][STREAM]the real action to close the stream,this=0x7f372c003f50 warning|2022-07-22 23:14:00.024959|[SDK][WS][SESSION][asyncRead],error=Operation canceled,endpoint=127.0.0.1:20200,session=0x7f372c0269d0 info|2022-07-22 23:14:00.025012|[SDK][WS][SESSION][drop]the session has already been dropped,endpoint=127.0.0.1:20200,session=0x7f372c0269d0 info|2022-07-22 23:14:00.025168|[SDK][WS][SERVICE][removeSession],endpoint=127.0.0.1:20200 info|2022-07-22 23:14:00.025192|[EVENT][SUB][suspendTasks]suspend event sub tasks for disconnection,endPoint=127.0.0.1:20200,count=0 info|2022-07-22 23:14:00.025202|[SDK][WS][SERVICE][onDisconnect],endpoint=127.0.0.1:20200,connectedEndPoint=127.0.0.1:20200,refCount=4 info|2022-07-22 23:14:00.025212|[RPCWS][SERVICE][clearGroupInfoByEp],endPoint=127.0.0.1:20200 info|2022-07-22 23:14:00.025228|[RPCWS][SERVICE][printGroupInfo],total count=0 info|2022-07-22 23:14:00.025238|[SDK][WS][SESSION],[DELOBJ][WSSESSION]=0x7f372c0269d0 info|2022-07-22 23:14:00.025419|[DEFAULT][WS][STREAM],[DELOBJ][WsStream]=0x7f372c003f50 info|2022-07-22 23:14:10.023960|[SDK][WS][SERVICE]reconnect,peer=127.0.0.1:20200 info|2022-07-22 23:14:10.024197|[SDK][WS][SERVICE]connected nodes,count=0 info|2022-07-22 23:14:10.024764|[DEFAULT][WS][CONNECTOR][connectToWsServer]async_connect success,endpoint=127.0.0.1:20200 info|2022-07-22 23:14:10.024915|[DEFAULT][WS][STREAM],[NEWOBJ][WsStream]=0x7f372c01f380 info|2022-07-22 23:14:10.029842|[DEFAULT][BOOSTSSL][NODEINFO][NEW]SSLContext pubHex: 3082010a0282010100b41cec3648b93cc7a67cbde69439076d1d947dea85169937c1ab23553de84e565bfbdaad764f22664950d19c65a2f1ddee17a62e6580cd83cde10641ee03734999a5271996cce3a5fc48e5529b71736cc38045f53decd18b4c5cda3dfaa406c33650bf17c1cb0d29e95227298b4d6aad6c0c787a261a8ba9ff20792c8154c64d6e76d882ecc879cf3fafb4ec86b06e7da17d5978cd231f93fae26006a85a8cf852fde90e874691d9cd3e8439b349aa0909f9233d5dbb1a2786d0d6d8375c1880e9aa1e8195bf721f1c9a3d1a2e409fe14b051e4dde6906a3ba819309fc99fe117eee5d59887d21942030d956db448f0824d266ee6466843824c9feb6b5285c1d0203010001 warning|2022-07-22 23:14:10.029902|[DEFAULT][BOOSTSSL][NODEINFO]Get ca basic failed info|2022-07-22 23:14:10.030087|[DEFAULT][BOOSTSSL][NODEINFO][NEW]SSLContext pubHex: 3082010a0282010100b753586713cba8114c17071cd3e9dadacbfec278cf27386cec0a3358641b99a255f6ab8fe4709cae373a2a0710214741728cfce4d06a81c6737354a56c4c1c0c610ed46858da56b0c15cf2677dbc32565a2142e1e4560e9a307051a1109af95b18ccecf5bcd7edcf4a1b0ba2ca75a0a9189c665b2bb86b044cd0eafbb91754dd785d70c0f4d7dc1b724abefdf0a6707711aaf6bfc55dcbbc604ed249968a6f1b3ad4ff884cd1be4af85b3ff965ed8e4c04be2ee87210681ba1652ec1ac1cbc73acc41b768c21c771a7a0ae322b19863ac714e44348e000aa13dfdeeed80e8793c9e0e46704c76b9a19b96873e2b86407311e2571282dfbebf06d34188dfa71510203010001 info|2022-07-22 23:14:10.036239|[DEFAULT][WS][CONNECTOR][connectToWsServer]ssl async_handshake success,host=127.0.0.1,port=20200 info|2022-07-22 23:14:10.036760|[DEFAULT][WS][CONNECTOR][connectToWsServer]websocket handshake successfully,host=127.0.0.1,port=20200 info|2022-07-22 23:14:10.036806|[SDK][WS][SESSION],[NEWOBJ][WSSESSION]=0x7f372c0233f0 info|2022-07-22 23:14:10.036820|[SDK][WS][SERVICE][newSession]start the session,endPoint=127.0.0.1:20200 info|2022-07-22 23:14:10.036831|[SDK][WS][SERVICE][addSession]add session to mapping,connectedEndPoint=127.0.0.1:20200,endPoint=127.0.0.1:20200,result=true info|2022-07-22 23:14:10.036839|[SDK][WS][SERVICE][onConnect],endpoint=127.0.0.1:20200,connectedEndPoint=127.0.0.1:20200,refCount=6 info|2022-07-22 23:14:10.036951|[RPCWS][SERVICE][startHandshake],endpoint=127.0.0.1:20200 info|2022-07-22 23:14:10.037152|[SDK][WS][SESSION][startAsClient]websocket handshake successfully,endPoint=127.0.0.1:20200,session=0x7f372c0233f0 warning|2022-07-22 23:14:20.037108|[SDK][WS][SESSION][onRespTimeout],seq=f31bea216fb546eba0243f7977bf6314 info|2022-07-22 23:14:20.037207|[SDK][WS][SESSION]127.0.0.1:20200 queueSize: 0 warning|2022-07-22 23:14:20.037247|[RPCWS][SERVICE][startHandshake]callback response error,endpoint=127.0.0.1:20200,errorCode=-4008,errorMessage=waiting for message response timed out info|2022-07-22 23:14:20.037300|[SDK][WS][SESSION][drop],reason=4294963289,endpoint=127.0.0.1:20200,session=0x7f372c0233f0 info|2022-07-22 23:14:20.037312|[SDK][WS][SESSION][drop],reason=4294963289,endpoint=127.0.0.1:20200,cb size=0,session=0x7f372c0233f0 info|2022-07-22 23:14:20.037436|[DEFAULT][WS][STREAM]the real action to close the stream,this=0x7f372c01f380 info|2022-07-22 23:14:20.037471|[SDK][WS][SERVICE][removeSession],endpoint=127.0.0.1:20200 info|2022-07-22 23:14:20.037482|[EVENT][SUB][suspendTasks]suspend event sub tasks for disconnection,endPoint=127.0.0.1:20200,count=0 info|2022-07-22 23:14:20.037490|[SDK][WS][SERVICE][onDisconnect],endpoint=127.0.0.1:20200,connectedEndPoint=127.0.0.1:20200,refCount=5 info|2022-07-22 23:14:20.037498|[RPCWS][SERVICE][clearGroupInfoByEp],endPoint=127.0.0.1:20200 info|2022-07-22 23:14:20.037506|[RPCWS][SERVICE][printGroupInfo],total count=0 warning|2022-07-22 23:14:20.037556|[SDK][WS][SESSION][asyncRead],error=Operation canceled,endpoint=127.0.0.1:20200,session=0x7f372c0233f0 info|2022-07-22 23:14:20.037584|[SDK][WS][SESSION][drop]the session has already been dropped,endpoint=127.0.0.1:20200,session=0x7f372c0233f0 info|2022-07-22 23:14:20.037594|[SDK][WS][SESSION],[DELOBJ][WSSESSION]=0x7f372c0233f0 info|2022-07-22 23:14:20.037740|[DEFAULT][WS][STREAM],[DELOBJ][WsStream]=0x7f372c01f380 info|2022-07-22 23:14:20.056984|[SDK][WS][SERVICE][stop]stop websocket service successfully warning|2022-07-22 23:14:20.057017|[RPCWS][SERVICE][waitForConnectionEstablish]wait for websocket connection handshake timeout,timeout=30000 error|2022-07-22 23:14:20.057150|[bcos_sdk_start],sdk=0x7f37a46c3da0,errorMsg=/github/home/.hunter/_Base/0ba8099/8cf628f/6cc1dfe/Build/bcos-cpp-sdk/Source/bcos-cpp-sdk/ws/Service.cpp(96): Throw in function void bcos::cppsdk::service::Service::waitForConnectionEstablish() Dynamic exception type: boost::wrapexceptstd::runtime_error std::exception::what: The websocket connection handshake timeout info|2022-07-22 23:14:20.057177|[bcos_sdk_start],sdk=0x7f37a46c3da0

  1. console.log

2022-07-22 23:01:45.819 INFO console.ConsoleInitializer 50 init -- Did not set group, use default group in config. 2022-07-22 23:01:45.923 DEBUG org.fisco.bcos.sdk.v3.config.model.CryptoMaterialConfig 111 -- Load cryptoMaterial, useSmCrypto: false, caCertPath: null, sdkCertPath: null, sdkPrivateKeyPath:null, enSSLCertPath: null, enSSLPrivateKeyPath:null 2022-07-22 23:01:45.927 INFO org.fisco.bcos.sdk.v3.config.model.NetworkConfig 44 -- network config items, timeout: 10000, peers: [127.0.0.1:20200] 2022-07-22 23:01:45.928 DEBUG org.fisco.bcos.sdk.v3.config.model.ThreadPoolConfig 44 -- Init ThreadPoolConfig, threadPoolSize: 4 2022-07-22 23:01:45.929 INFO org.fisco.bcos.sdk.v3.BcosSDK 47 build -- create BcosSDK, configPath: /root/fisco/console/conf/config.toml 2022-07-22 23:01:45.931 INFO org.fisco.bcos.sdk.jni.common.JniLibLoader 72 -- initialize workdir, -Djava.io.tmpdirs: /tmp 2022-07-22 23:01:45.931 INFO org.fisco.bcos.sdk.jni.common.JniLibLoader 143 loadJniLibrary -- try to load library from jar 2022-07-22 23:01:45.931 DEBUG org.fisco.bcos.sdk.jni.common.JniLibLoader 89 getLibName -- osName: linux, archName: 2022-07-22 23:01:45.932 INFO org.fisco.bcos.sdk.jni.common.JniLibLoader 192 loadLibraryFromJar -- tempDir: /tmp/104868143810118, tempFile: /tmp/104868143810118/libbcos-sdk-jni.so 2022-07-22 23:01:46.143 DEBUG org.fisco.bcos.sdk.jni.common.JniLibLoader 218 loadLibraryFromJar -- remove temp dir and temp file, /tmp/104868143810118 2022-07-22 23:01:46.148 INFO org.fisco.bcos.sdk.jni.BcosSDKJniObj 40 build -- newNativePointer, nativePointer: 140134522504768, jniConfig: JniConfig{threadPoolSize=4, reconnectPeriodMs=20000, heartbeatPeriodMs=20000, messageTimeoutMs=10000, disableSsl=false, sslType='ssl', peers='[127.0.0.1:20200]'} 2022-07-22 23:01:46.149 INFO org.fisco.bcos.sdk.v3.client.Client 91 build -- build, groupID: group0, configOption: org.fisco.bcos.sdk.v3.config.ConfigOption@441772e, nativePointer: 140134522504768 2022-07-22 23:01:46.351 INFO org.fisco.bcos.sdk.jni.rpc.RpcJniObj 27 build -- nativePointer: 140134522504768 2022-07-22 23:02:16.397 WARN org.fisco.bcos.sdk.v3.BcosSDK 109 getClient -- create client for failed, error: org.fisco.bcos.sdk.jni.common.JniException: /github/home/.hunter/_Base/0ba8099/8cf628f/6cc1dfe/Build/bcos-cpp-sdk/Source/bcos-cpp-sdk/ws/Service.cpp(96): Throw in function void bcos::cppsdk::service::Service::waitForConnectionEstablish() Dynamic exception type: boost::wrapexceptstd::runtime_error std::exception::what: The websocket connection handshake timeout

    at org.fisco.bcos.sdk.jni.rpc.RpcJniObj.start(Native Method) ~[bcos-sdk-jni-3.0.0-rc4.jar:?]
    at org.fisco.bcos.sdk.v3.client.ClientImpl.start(ClientImpl.java:1064) ~[fisco-bcos-java-sdk-3.0.0-rc4.jar:?]
    at org.fisco.bcos.sdk.v3.client.ClientImpl.<init>(ClientImpl.java:136) ~[fisco-bcos-java-sdk-3.0.0-rc4.jar:?]
    at org.fisco.bcos.sdk.v3.client.Client.build(Client.java:96) ~[fisco-bcos-java-sdk-3.0.0-rc4.jar:?]
    at org.fisco.bcos.sdk.v3.BcosSDK.getClient(BcosSDK.java:104) [fisco-bcos-java-sdk-3.0.0-rc4.jar:?]
    at console.ConsoleInitializer.loadAccountInfo(ConsoleInitializer.java:141) [console.jar:?]
    at console.ConsoleInitializer.init(ConsoleInitializer.java:60) [console.jar:?]
    at console.Console.main(Console.java:49) [console.jar:?]

2022-07-22 23:02:16.404 ERROR console.ConsoleInitializer 176 loadAccountInfo -- message: get Client failed, e: /github/home/.hunter/_Base/0ba8099/8cf628f/6cc1dfe/Build/bcos-cpp-sdk/Source/bcos-cpp-sdk/ws/Service.cpp(96): Throw in function void bcos::cppsdk::service::Service::waitForConnectionEstablish() Dynamic exception type: boost::wrapexceptstd::runtime_error std::exception::what: The websocket connection handshake timeout , e: org.fisco.bcos.sdk.v3.BcosSDKException: get Client failed, e: /github/home/.hunter/_Base/0ba8099/8cf628f/6cc1dfe/Build/bcos-cpp-sdk/Source/bcos-cpp-sdk/ws/Service.cpp(96): Throw in function void bcos::cppsdk::service::Service::waitForConnectionEstablish() Dynamic exception type: boost::wrapexceptstd::runtime_error std::exception::what: The websocket connection handshake timeout

mykidxiao avatar Jul 22 '22 15:07 mykidxiao