besu Full sync error on new rpcnode

Full sync error on new rpcnode

Open panicstyle opened this issue 2 years ago • 7 comments

A full sync of the new rpcnode does not proceed.

validator node : 4
besu version : 22.4.1

Both validator and rpcnode are configured on the same host, and are executed using docker-compose.yml. static-nodes and permissions-nodes-config-file are also set.

Attach the rpcnode debug log.

Setting logging level to INFO
{"timestamp":"2022-06-05T09:21:44,768","container":"61fc5316e176","level":"INFO","thread":"main","class":"Besu","message":"Using LibEthPairings native alt bn128","throwable":""}
{"timestamp":"2022-06-05T09:21:44,780","container":"61fc5316e176","level":"INFO","thread":"main","class":"Besu","message":"Using the native implementation of the signature algorithm","throwable":""}
{"timestamp":"2022-06-05T09:21:44,787","container":"61fc5316e176","level":"INFO","thread":"main","class":"Besu","message":"Starting Besu version: besu/v22.4.1/linux-x86_64/openjdk-java-11","throwable":""}
{"timestamp":"2022-06-05T09:21:48,113","container":"61fc5316e176","level":"INFO","thread":"main","class":"Besu","message":"Static Nodes file = /config/static-nodes.json","throwable":""}
{"timestamp":"2022-06-05T09:21:48,278","container":"61fc5316e176","level":"INFO","thread":"main","class":"Besu","message":"Connecting to 10 static nodes.","throwable":""}
{"timestamp":"2022-06-05T09:21:48,318","container":"61fc5316e176","level":"INFO","thread":"main","class":"Besu","message":"Security Module: localfile","throwable":""}
{"timestamp":"2022-06-05T09:21:48,409","container":"61fc5316e176","level":"INFO","thread":"main","class":"DatabaseMetadata","message":"Lookup database metadata file in data directory: /opt/besu/data","throwable":""}
{"timestamp":"2022-06-05T09:21:48,713","container":"61fc5316e176","level":"INFO","thread":"main","class":"RocksDBKeyValueStorageFactory","message":"Existing database detected at /opt/besu/data. Version 1","throwable":""}
{"timestamp":"2022-06-05T09:21:59,002","container":"61fc5316e176","level":"INFO","thread":"main","class":"KeyPairUtil","message":"Loaded public key 0x0bbe650ff1fbbf6c568386b48528596a52ecd6e961dce54650c1c4497c8e867943e531104869c0d7a82135dec54ab0f778d92bd84c1ca3e38b07f737eb80e3b3 from /opt/besu/keys/nodekey","throwable":""}
{"timestamp":"2022-06-05T09:21:59,014","container":"61fc5316e176","level":"WARN","thread":"main","class":"Besu","message":"Privacy is disabled. Cannot use EEA/PRIV API methods when not using Privacy.","throwable":""}
{"timestamp":"2022-06-05T09:21:59,777","container":"61fc5316e176","level":"INFO","thread":"main","class":"ProtocolScheduleBuilder","message":"Protocol schedule created with milestones: [London: 0, London: 1203673]","throwable":""}
{"timestamp":"2022-06-05T09:22:00,412","container":"61fc5316e176","level":"INFO","thread":"main","class":"BesuControllerBuilder","message":"TTD difficulty is not present, creating initial sync phase for PoW","throwable":""}
{"timestamp":"2022-06-05T09:22:00,612","container":"61fc5316e176","level":"INFO","thread":"main","class":"RunnerBuilder","message":"Detecting NAT service.","throwable":""}
{"timestamp":"2022-06-05T09:22:04,035","container":"61fc5316e176","level":"INFO","thread":"main","class":"Runner","message":"Starting external services ... ","throwable":""}
{"timestamp":"2022-06-05T09:22:04,038","container":"61fc5316e176","level":"INFO","thread":"main","class":"MetricsHttpService","message":"Starting metrics http service on 0.0.0.0:9545","throwable":""}
{"timestamp":"2022-06-05T09:22:04,822","container":"61fc5316e176","level":"INFO","thread":"vert.x-eventloop-thread-1","class":"MetricsHttpService","message":"Metrics service started and listening on 0.0.0.0:9545","throwable":""}
{"timestamp":"2022-06-05T09:22:04,825","container":"61fc5316e176","level":"INFO","thread":"main","class":"JsonRpcHttpService","message":"Starting JSON-RPC service on 0.0.0.0:8545","throwable":""}
{"timestamp":"2022-06-05T09:22:04,952","container":"61fc5316e176","level":"INFO","thread":"vert.x-eventloop-thread-1","class":"JsonRpcHttpService","message":"JSON-RPC service started and listening on 0.0.0.0:8545","throwable":""}
{"timestamp":"2022-06-05T09:22:04,954","container":"61fc5316e176","level":"INFO","thread":"main","class":"GraphQLHttpService","message":"Starting GraphQL HTTP service on 0.0.0.0:8547","throwable":""}
{"timestamp":"2022-06-05T09:22:04,960","container":"61fc5316e176","level":"INFO","thread":"vert.x-eventloop-thread-1","class":"GraphQLHttpService","message":"GraphQL HTTP service started and listening on 0.0.0.0:8547","throwable":""}
{"timestamp":"2022-06-05T09:22:04,972","container":"61fc5316e176","level":"INFO","thread":"main","class":"WebSocketService","message":"Starting Websocket service on 0.0.0.0:8546","throwable":""}
{"timestamp":"2022-06-05T09:22:05,009","container":"61fc5316e176","level":"INFO","thread":"vert.x-eventloop-thread-1","class":"WebSocketService","message":"Websocket service started and listening on 0.0.0.0:8546","throwable":""}
{"timestamp":"2022-06-05T09:22:05,016","container":"61fc5316e176","level":"INFO","thread":"main","class":"AutoTransactionLogBloomCachingService","message":"Starting auto transaction log bloom caching service.","throwable":""}
{"timestamp":"2022-06-05T09:22:05,033","container":"61fc5316e176","level":"INFO","thread":"main","class":"LogBloomCacheMetadata","message":"Lookup cache metadata file in data directory: /opt/besu/data/caches","throwable":""}
{"timestamp":"2022-06-05T09:22:05,086","container":"61fc5316e176","level":"INFO","thread":"main","class":"Runner","message":"Starting Ethereum main loop ... ","throwable":""}
{"timestamp":"2022-06-05T09:22:05,092","container":"61fc5316e176","level":"INFO","thread":"main","class":"DockerNatManager","message":"Starting docker NAT manager.","throwable":""}
{"timestamp":"2022-06-05T09:22:05,118","container":"61fc5316e176","level":"INFO","thread":"main","class":"NetworkRunner","message":"Starting Network.","throwable":""}
{"timestamp":"2022-06-05T09:22:05,269","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-2-1","class":"RlpxAgent","message":"P2P RLPx agent started and listening on /0.0.0.0:30303.","throwable":""}
{"timestamp":"2022-06-05T09:22:05,271","container":"61fc5316e176","level":"INFO","thread":"main","class":"PeerDiscoveryAgent","message":"Starting peer discovery agent on host=0.0.0.0, port=30303","throwable":""}
{"timestamp":"2022-06-05T09:22:05,545","container":"61fc5316e176","level":"INFO","thread":"vert.x-eventloop-thread-1","class":"VertxPeerDiscoveryAgent","message":"Started peer discovery agent successfully, on effective host=0.0.0.0 and port=30303","throwable":""}
{"timestamp":"2022-06-05T09:22:05,554","container":"61fc5316e176","level":"INFO","thread":"main","class":"PeerDiscoveryAgent","message":"P2P peer discovery agent started and listening on /0.0.0.0:30303","throwable":""}
{"timestamp":"2022-06-05T09:22:05,926","container":"61fc5316e176","level":"INFO","thread":"main","class":"PeerDiscoveryAgent","message":"Writing node record to disk. NodeRecord{seq=3, publicKey=0x030bbe650ff1fbbf6c568386b48528596a52ecd6e961dce54650c1c4497c8e8679, udpAddress=Optional[/172.16.239.15:30303], tcpAddress=Optional[/172.16.239.15:30303], asBase64=-Je4QLa2M-ykaXuIOnNslehb4LFZTUddqZbG9jnj2JiqspWnLU7iz69ssUhhPuRJ7U93YFxTXpi_XgSBxnKBnlBLbHoDg2V0aMfGhPYzeJqAgmlkgnY0gmlwhKwQ7w-Jc2VjcDI1NmsxoQMLvmUP8fu_bFaDhrSFKFlqUuzW6WHc5UZQwcRJfI6GeYN0Y3CCdl-DdWRwgnZf, nodeId=0x910ffd25cd3ddaf6e65f16690a120666165e4b034db824cce7fefdd2c3d55459, customFields={tcp=30303, udp=30303, ip=0xac10ef0f, eth=[[0xf633789a, 0x]], id=V4, secp256k1=0x030bbe650ff1fbbf6c568386b48528596a52ecd6e961dce54650c1c4497c8e8679}}","throwable":""}
{"timestamp":"2022-06-05T09:22:06,350","container":"61fc5316e176","level":"INFO","thread":"main","class":"DefaultP2PNetwork","message":"Enode URL enode://0bbe650ff1fbbf6c568386b48528596a52ecd6e961dce54650c1c4497c8e867943e531104869c0d7a82135dec54ab0f778d92bd84c1ca3e38b07f737eb80e3b3@172.16.239.15:30303","throwable":""}
{"timestamp":"2022-06-05T09:22:06,352","container":"61fc5316e176","level":"INFO","thread":"main","class":"DefaultP2PNetwork","message":"Node address 0x0a120666165e4b034db824cce7fefdd2c3d55459","throwable":""}
{"timestamp":"2022-06-05T09:22:06,373","container":"61fc5316e176","level":"INFO","thread":"main","class":"DefaultSynchronizer","message":"Starting synchronizer.","throwable":""}
{"timestamp":"2022-06-05T09:22:06,379","container":"61fc5316e176","level":"INFO","thread":"main","class":"FullSyncDownloader","message":"Starting full sync.","throwable":""}
{"timestamp":"2022-06-05T09:22:06,385","container":"61fc5316e176","level":"INFO","thread":"main","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:22:09,906","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-1","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 1","throwable":""}
{"timestamp":"2022-06-05T09:22:11,350","container":"61fc5316e176","level":"INFO","thread":"main","class":"Runner","message":"Ethereum main loop is up.","throwable":""}
{"timestamp":"2022-06-05T09:22:12,933","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-1","class":"SyncTargetManager","message":"Found common ancestor with peer Peer 0x88da9cd57096ec7cd7... PeerReputation 100, validated? true, disconnected? false at block 2200","throwable":""}
{"timestamp":"2022-06-05T09:23:16,270","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-1","class":"SyncTargetManager","message":"Found common ancestor with peer Peer 0xeb71f3cd2cda7e7be9... PeerReputation 100, validated? true, disconnected? false at block 2200","throwable":""}
{"timestamp":"2022-06-05T09:23:31,339","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-1","class":"SyncTargetManager","message":"Found common ancestor with peer Peer 0x88da9cd57096ec7cd7... PeerReputation 100, validated? true, disconnected? false at block 2200","throwable":""}
{"timestamp":"2022-06-05T09:23:40,385","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Workers-1","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:23:41,376","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:23:41,379","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:23:41,383","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:23:42,502","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-4","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 1","throwable":""}
{"timestamp":"2022-06-05T09:23:42,505","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-4","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 1","throwable":""}
{"timestamp":"2022-06-05T09:23:42,508","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-4","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 1","throwable":""}
{"timestamp":"2022-06-05T09:23:42,508","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-4","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 1","throwable":""}
{"timestamp":"2022-06-05T09:23:48,960","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Services-6 (importBlock)","class":"FullImportBlockStep","message":"Import reached block 2400 (0x6335900898ea5d018067b3865c9aaf4cefe77a202ce097780f4a924ec0383028), - Mg/s, Peers: 1","throwable":""}
{"timestamp":"2022-06-05T09:23:52,511","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Workers-3","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:23:55,512","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:23:57,512","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:00,512","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:02,513","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:05,513","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:06,421","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-8","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 1","throwable":""}
{"timestamp":"2022-06-05T09:24:21,423","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:26,425","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:31,426","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:36,427","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:41,427","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:46,429","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:51,430","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:24:56,431","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:01,431","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:06,432","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:11,433","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:16,434","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:21,435","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:26,436","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:31,437","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:36,437","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:41,438","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:46,439","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:51,440","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:25:56,441","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:01,441","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:06,235","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-10","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 1","throwable":""}
{"timestamp":"2022-06-05T09:26:11,281","container":"61fc5316e176","level":"INFO","thread":"nioEventLoopGroup-3-10","class":"SyncTargetManager","message":"Found common ancestor with peer Peer 0x88da9cd57096ec7cd7... PeerReputation 100, validated? true, disconnected? false at block 2400","throwable":""}
{"timestamp":"2022-06-05T09:26:16,706","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Workers-2","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:17,663","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:17,664","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:17,707","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:21,708","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:22,664","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:22,665","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:22,709","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:26,712","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:27,665","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:27,666","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:27,711","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"AbstractRetryingPeerTask","message":"No useful peer found, checking remaining current peers for usefulness: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:34,714","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:39,715","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:44,716","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:49,717","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:54,718","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:26:59,718","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:27:04,719","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:27:09,720","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:27:14,721","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:27:19,722","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:27:24,724","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-06-05T09:27:29,725","container":"61fc5316e176","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}

Jun 06 '22 02:06 panicstyle

Hi there @panicstyle - Can you provide us more info about your set-up here? Can we have your startup info/config for your network?

Jun 21 '22 16:06 non-fungible-nelson

Can u tell us what is the your config on --nat-method ?

Jul 19 '22 08:07 eu09

Sorry. The answer was late. There was no problem at first, but after a few weeks it won't connect. There is no problem in connection between validators. Only rpcnode1 can't connect.

See below command :

  /opt/besu/bin/besu \
  --config-file=/config/config.toml \
  --p2p-host=$$(hostname -i) \
  --rpc-http-api=EEA,WEB3,ETH,NET,TRACE,DEBUG,ADMIN,PERM,${BESU_CONS_ALGO} \
  --rpc-ws-api=EEA,WEB3,ETH,NET,TRACE,DEBUG,ADMIN,PERM,${BESU_CONS_ALGO} ;

And attach config.toml below.

genesis-file="/config/genesis.json"
node-private-key-file="/opt/besu/keys/nodekey"
logging="DEBUG"
data-path="/opt/besu/data"
host-allowlist=["*"]
min-gas-price=0
revert-reason-enabled=true

# rpc
rpc-http-enabled=true
rpc-http-host="0.0.0.0"
rpc-http-port=8545
rpc-http-cors-origins=["*"]

# ws
rpc-ws-enabled=true
rpc-ws-host="0.0.0.0"
rpc-ws-port=8546

# graphql
graphql-http-enabled=true
graphql-http-host="0.0.0.0"
graphql-http-port=8547
graphql-http-cors-origins=["*"]

# metrics
metrics-enabled=true
metrics-host="0.0.0.0"
metrics-port=9545

# permissions
#permissions-nodes-config-file-enabled=false
#permissions-nodes-config-file="/config/permissions_config.toml"

# bootnodes
bootnodes=[ "enode://afc174836fca67a36752fd071bead3152a3f31eba1b4b807fc6861d86fc47ab81b9d1e7836f4ddefa8086bc005f1d856b9c82f6344e12706fb10ed8b7d1fbc23@172.16.239.11:30303" ]


# Discovery at boot is set to a list of static files, but will also discover new nodes should they be added
# static nodes
static-nodes-file="/config/static-nodes.json"
discovery-enabled=true

docker-compose.yml file

x-besu-def:
  &besu-def
  restart: "on-failure"
  image: hyperledger/besu:${BESU_VERSION:-latest}
  env_file:
    - ./config/besu/.env
  entrypoint:
    - /bin/bash
    - -c
    - |

      /opt/besu/bin/besu \
      --config-file=/config/config.toml \
      --p2p-host=$$(hostname -i) \
      --rpc-http-api=EEA,WEB3,ETH,NET,TRACE,DEBUG,ADMIN,PERM,${BESU_CONS_ALGO} \
      --rpc-ws-api=EEA,WEB3,ETH,NET,TRACE,DEBUG,ADMIN,PERM,${BESU_CONS_ALGO} ;

services:

  validator1:
    << : *besu-def
    ports:
      - 21001:8545/tcp
      - 30303:30303/tcp
      - 30303:30303/udp
      - 9545
    container_name: validator1
    environment:
      - OTEL_RESOURCE_ATTRIBUTES=service.name=validator1,service.version=${BESU_VERSION:-latest}
    labels:
      - "consensus=besu"      
    volumes:
      - ./config/besu/:/config
      - ./config/nodes/validator1:/opt/besu/keys
      - ./data/validator1:/opt/besu/data
    networks:
      finx-net:
        ipv4_address: 172.16.239.11

  validator2:
    << : *besu-def
    ports:
      - 21002:8545/tcp
      - 30304:30303/tcp
      - 30304:30303/udp
      - 9545    
    container_name: validator2
    environment:
      - OTEL_RESOURCE_ATTRIBUTES=service.name=validator2,service.version=${BESU_VERSION:-latest}
    labels:
      - "consensus=besu"      
    volumes:
      - ./config/besu/:/config
      - ./config/nodes/validator2:/opt/besu/keys
      - ./data/validator2:/opt/besu/data
    depends_on:
      - validator1
    networks:
      finx-net:
        ipv4_address: 172.16.239.12

  validator3:
    << : *besu-def
    ports:
      - 21003:8545/tcp
      - 30305:30303/tcp
      - 30305:30303/udp
      - 9545    
    container_name: validator3
    environment:
      - OTEL_RESOURCE_ATTRIBUTES=service.name=validator3,service.version=${BESU_VERSION:-latest}
    labels:
      - "consensus=besu"      
    volumes:
      - ./config/besu/:/config
      - ./config/nodes/validator3:/opt/besu/keys
      - ./data/validator3:/opt/besu/data
    depends_on:
      - validator1
    networks:
      finx-net:
        ipv4_address: 172.16.239.13

  validator4:
    << : *besu-def
    ports:
      - 21004:8545/tcp
      - 30306:30303/tcp
      - 30306:30303/udp
      - 9545    
    container_name: validator4
    environment:
      - OTEL_RESOURCE_ATTRIBUTES=service.name=validator4,service.version=${BESU_VERSION:-latest}
    labels:
      - "consensus=besu"      
    volumes:
      - ./config/besu/:/config
      - ./config/nodes/validator4:/opt/besu/keys
      - ./data/validator4:/opt/besu/data
    depends_on:
      - validator1
    networks:
      finx-net:
        ipv4_address: 172.16.239.14

  rpcnode1:
    << : *besu-def
    container_name: rpcnode1
    environment:
      - OTEL_RESOURCE_ATTRIBUTES=service.name=rpcnode1,service.version=${BESU_VERSION:-latest}
    volumes:
      - ./config/besu/:/config
      - ./config/nodes/rpcnode1:/opt/besu/keys
      - ./data/rpcnode1:/opt/besu/data
    depends_on:
      - validator1
    ports:
      - 8545:8545/tcp
      - 8546:8546/tcp
      - 30307:30303/tcp
      - 30307:30303/udp
    networks:
      finx-net:
        ipv4_address: 172.16.239.15

networks:
  finx-net:
    name: finx-net
    driver: bridge
    ipam:
      config:
        - subnet: 172.16.239.0/24

Aug 02 '22 07:08 panicstyle

and attach debug log of rpcnode

{"timestamp":"2022-08-02T08:01:18,350","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-7","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0xeb71f3cd2cda7e7be9405b9d369aaf40cdd59dd69b4cfebfd54fa5729393ac04c4e4671aca7c231f5477e4069b08509cf919f37264bbd1e341fc59c166f67521} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:18,351","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-7","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0xeb71f3cd2cda7e7be9405b9d369aaf40cdd59dd69b4cfebfd54fa5729393ac04c4e4671aca7c231f5477e4069b08509cf919f37264bbd1e341fc59c166f67521, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:18,942","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-worker-thread-17","class":"PingPacketData","message":"read PING enr as long scalar","throwable":""}
{"timestamp":"2022-08-02T08:01:21,512","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-8","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0x88da9cd57096ec7cd77ad0282168b22199c216a4f0f86e06734d527b80569543d8906acaa18510316b0cab908be8d5f4f0f9b9ab628f291cfc9cc8f473ed9d90, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:21,512","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-8","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x88da9cd57096ec7cd77ad0282168b22199c216a4f0f86e06734d527b80569543d8906acaa18510316b0cab908be8d5f4f0f9b9ab628f291cfc9cc8f473ed9d90} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:21,512","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-8","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x88da9cd57096ec7cd77ad0282168b22199c216a4f0f86e06734d527b80569543d8906acaa18510316b0cab908be8d5f4f0f9b9ab628f291cfc9cc8f473ed9d90} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:21,513","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-8","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0x88da9cd57096ec7cd77ad0282168b22199c216a4f0f86e06734d527b80569543d8906acaa18510316b0cab908be8d5f4f0f9b9ab628f291cfc9cc8f473ed9d90, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:22,226","container":"3c603695dcda","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-08-02T08:01:22,226","container":"3c603695dcda","level":"DEBUG","thread":"EthScheduler-Timer-0","class":"WaitForPeerTask","message":"Waiting for new peer connection. 0 peers currently connected.","throwable":""}
{"timestamp":"2022-08-02T08:01:25,109","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-9","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0x3344a3cfcfc4b6fb801bd7c424420a8ca6856ee92352b526025a2eede41feafdd69670647c266917b9c2d1a64c223f5cd095a8832739bd9253bc31b3ddad1932, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:25,109","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-9","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x3344a3cfcfc4b6fb801bd7c424420a8ca6856ee92352b526025a2eede41feafdd69670647c266917b9c2d1a64c223f5cd095a8832739bd9253bc31b3ddad1932} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:25,109","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-9","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x3344a3cfcfc4b6fb801bd7c424420a8ca6856ee92352b526025a2eede41feafdd69670647c266917b9c2d1a64c223f5cd095a8832739bd9253bc31b3ddad1932} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:25,110","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-9","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0x3344a3cfcfc4b6fb801bd7c424420a8ca6856ee92352b526025a2eede41feafdd69670647c266917b9c2d1a64c223f5cd095a8832739bd9253bc31b3ddad1932, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:25,692","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-worker-thread-16","class":"PingPacketData","message":"read PING enr as long scalar","throwable":""}
{"timestamp":"2022-08-02T08:01:27,226","container":"3c603695dcda","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-08-02T08:01:27,226","container":"3c603695dcda","level":"DEBUG","thread":"EthScheduler-Timer-0","class":"WaitForPeerTask","message":"Waiting for new peer connection. 0 peers currently connected.","throwable":""}
{"timestamp":"2022-08-02T08:01:31,187","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-eventloop-thread-3","class":"PeerDiscoveryController","message":"Peer table refresh triggered by insufficient peers","throwable":""}
{"timestamp":"2022-08-02T08:01:31,187","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-eventloop-thread-3","class":"RecursivePeerRefreshState","message":"Start peer search.","throwable":""}
{"timestamp":"2022-08-02T08:01:31,188","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-eventloop-thread-3","class":"RecursivePeerRefreshState","message":"Skipping bonding round because no candidates are available","throwable":""}
{"timestamp":"2022-08-02T08:01:31,188","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-eventloop-thread-3","class":"RecursivePeerRefreshState","message":"Iterative peer search complete.  0 peers processed over 1 rounds.","throwable":""}
{"timestamp":"2022-08-02T08:01:32,226","container":"3c603695dcda","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-08-02T08:01:32,226","container":"3c603695dcda","level":"DEBUG","thread":"EthScheduler-Timer-0","class":"WaitForPeerTask","message":"Waiting for new peer connection. 0 peers currently connected.","throwable":""}
{"timestamp":"2022-08-02T08:01:33,148","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-10","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0x5b6e4151fc06760164faef86a750c4551e5169b7073bb5a531ef5352b7f32a09c594b19c3f9b12b2e0b4404c0998938b898b1010029ee86b4c7455511525dfe8, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:33,149","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-10","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x5b6e4151fc06760164faef86a750c4551e5169b7073bb5a531ef5352b7f32a09c594b19c3f9b12b2e0b4404c0998938b898b1010029ee86b4c7455511525dfe8} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:33,149","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-10","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x5b6e4151fc06760164faef86a750c4551e5169b7073bb5a531ef5352b7f32a09c594b19c3f9b12b2e0b4404c0998938b898b1010029ee86b4c7455511525dfe8} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:33,149","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-10","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0x5b6e4151fc06760164faef86a750c4551e5169b7073bb5a531ef5352b7f32a09c594b19c3f9b12b2e0b4404c0998938b898b1010029ee86b4c7455511525dfe8, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:35,817","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-worker-thread-7","class":"PingPacketData","message":"read PING enr as long scalar","throwable":""}
{"timestamp":"2022-08-02T08:01:37,226","container":"3c603695dcda","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-08-02T08:01:37,227","container":"3c603695dcda","level":"DEBUG","thread":"EthScheduler-Timer-0","class":"WaitForPeerTask","message":"Waiting for new peer connection. 0 peers currently connected.","throwable":""}
{"timestamp":"2022-08-02T08:01:38,901","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-1","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0x4c8b0c59b1d4a791072c3112befbf4c54e0acd8aa8bd10f87a0e31869e924b7e12c196b3228fc75f409a976a8160e3fe1e848b29b9b297b3afb4977d093de19e, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:38,901","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-1","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x4c8b0c59b1d4a791072c3112befbf4c54e0acd8aa8bd10f87a0e31869e924b7e12c196b3228fc75f409a976a8160e3fe1e848b29b9b297b3afb4977d093de19e} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:38,901","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-1","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x4c8b0c59b1d4a791072c3112befbf4c54e0acd8aa8bd10f87a0e31869e924b7e12c196b3228fc75f409a976a8160e3fe1e848b29b9b297b3afb4977d093de19e} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:38,901","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-1","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0x4c8b0c59b1d4a791072c3112befbf4c54e0acd8aa8bd10f87a0e31869e924b7e12c196b3228fc75f409a976a8160e3fe1e848b29b9b297b3afb4977d093de19e, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:38,965","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-2","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0x96728818d6b68754a702bb3f8b03ae39a2c18238d6026675fdfc75d336758e0307e967db3f3e90207e95ad6f5fc9606276b074cee00c3ca7cda691bab08a5d5b, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:38,965","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-2","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x96728818d6b68754a702bb3f8b03ae39a2c18238d6026675fdfc75d336758e0307e967db3f3e90207e95ad6f5fc9606276b074cee00c3ca7cda691bab08a5d5b} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:38,965","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-2","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x96728818d6b68754a702bb3f8b03ae39a2c18238d6026675fdfc75d336758e0307e967db3f3e90207e95ad6f5fc9606276b074cee00c3ca7cda691bab08a5d5b} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:38,966","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-2","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0x96728818d6b68754a702bb3f8b03ae39a2c18238d6026675fdfc75d336758e0307e967db3f3e90207e95ad6f5fc9606276b074cee00c3ca7cda691bab08a5d5b, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:39,339","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-3","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0x6974cfe33336cc5e67662cfd9a3c54535f5f929fed934a9d11195f18c752df18a89e72d070d7df0f7d96c35f31f68ae71805d47c093a678dd541cb4ff597dafd, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:39,339","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-3","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x6974cfe33336cc5e67662cfd9a3c54535f5f929fed934a9d11195f18c752df18a89e72d070d7df0f7d96c35f31f68ae71805d47c093a678dd541cb4ff597dafd} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:39,339","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-3","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0x6974cfe33336cc5e67662cfd9a3c54535f5f929fed934a9d11195f18c752df18a89e72d070d7df0f7d96c35f31f68ae71805d47c093a678dd541cb4ff597dafd} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:39,340","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-3","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0x6974cfe33336cc5e67662cfd9a3c54535f5f929fed934a9d11195f18c752df18a89e72d070d7df0f7d96c35f31f68ae71805d47c093a678dd541cb4ff597dafd, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:39,441","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-worker-thread-11","class":"PingPacketData","message":"read PING enr as long scalar","throwable":""}
{"timestamp":"2022-08-02T08:01:41,056","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-4","class":"RlpxAgent","message":"Node is not permitted to connect. Disconnect incoming connection: NettyPeerConnection{nodeId=0xacbd770360df7754567bf466453f102ffadee2ef0f7cb3b4176557848762eade22d73e5886b3f8d1f3625bd039c1aa3db6c49ad52a3e62443804105ae256d9f3, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:41,056","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-4","class":"EthProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0xacbd770360df7754567bf466453f102ffadee2ef0f7cb3b4176557848762eade22d73e5886b3f8d1f3625bd039c1aa3db6c49ad52a3e62443804105ae256d9f3} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:41,057","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-4","class":"SnapProtocolManager","message":"Disconnect - Outbound - 0x UNKNOWN - PeerInfo{version=5, clientId='besu/v22.4.3/linux-x86_64/openjdk-java-11', capabilities=[eth/62, eth/63, eth/64, eth/65, eth/66, istanbul/100, snap/1], port=30303, nodeId=0xacbd770360df7754567bf466453f102ffadee2ef0f7cb3b4176557848762eade22d73e5886b3f8d1f3625bd039c1aa3db6c49ad52a3e62443804105ae256d9f3} - 0 peers left","throwable":""}
{"timestamp":"2022-08-02T08:01:41,057","container":"3c603695dcda","level":"DEBUG","thread":"nioEventLoopGroup-3-4","class":"EthProtocolManager","message":"Ignoring message received from unknown peer connection: NettyPeerConnection{nodeId=0xacbd770360df7754567bf466453f102ffadee2ef0f7cb3b4176557848762eade22d73e5886b3f8d1f3625bd039c1aa3db6c49ad52a3e62443804105ae256d9f3, clientId=besu/v22.4.3/linux-x86_64/openjdk-java-11, caps=eth/66, istanbul/100, snap/1}","throwable":""}
{"timestamp":"2022-08-02T08:01:41,442","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-worker-thread-15","class":"PingPacketData","message":"read PING enr as long scalar","throwable":""}
{"timestamp":"2022-08-02T08:01:42,227","container":"3c603695dcda","level":"INFO","thread":"EthScheduler-Timer-0","class":"FullSyncTargetManager","message":"No sync target, waiting for peers. Current peers: 0","throwable":""}
{"timestamp":"2022-08-02T08:01:42,227","container":"3c603695dcda","level":"DEBUG","thread":"EthScheduler-Timer-0","class":"WaitForPeerTask","message":"Waiting for new peer connection. 0 peers currently connected.","throwable":""}
{"timestamp":"2022-08-02T08:01:44,442","container":"3c603695dcda","level":"DEBUG","thread":"vert.x-worker-thread-14","class":"PingPacketData","message":"read PING enr as long scalar","throwable":""}
ubuntu@ip-192-168-152-22:~/finenex-net-1/config/besu$

Aug 02 '22 08:08 panicstyle

Why does node is not permitted to connect even when permission is not set?

add TRACE LEVEL log with besu version is 22.7.0

rpcnode1-trace.log

Aug 12 '22 15:08 panicstyle

Dear.

I do not understand what you are requesting. Are you trying to access the node using curl? like this curl http://127.0.0.1:30303 ?

It is the same phenomenon even though both permission setting and static-node setting are disabled now. When the added node is executed for the first time, it is synced at first and receives some block data, but after that, connection is not possible.

The current besu version is 22.7.0 . I am attaching an additional trace log.

rpcnode1.log

Thank you.

오전 1:07, Daniel Lehrner @.***> 작성:

@panicstyle https://github.com/panicstyle How are you calling the RPC endpoint? Could you povide us with an example (curl or similar)?

— Reply to this email directly, view it on GitHub https://github.com/hyperledger/besu/issues/3938#issuecomment-1213277666, or unsubscribe https://github.com/notifications/unsubscribe-auth/AAC3MMTMEORMJIOPIIXLIODVYZZCPANCNFSM5X6ARTWA. You are receiving this because you were mentioned.

Aug 13 '22 04:08 panicstyle

I checked the logs a bit more.Restarting node causes it to sync very slowly. After a while, it doesn't sync anymore. The sync cannot be achieved because the blockperiod is set to 1 second in the configuration in genesis.json.

Is this normal?

rpcnode-trace.log

Aug 13 '22 16:08 panicstyle

@panicstyle we have made improvements to peering (among other things) in later besu versions. Are you able to try with latest besu?

blockperiod 1s might be too short? did you try with 3 or 5s?

Jan 16 '23 08:01 macfarla

closing for now - is this still an issue on latest? Please reopen @panicstyle if so.

Jan 26 '23 12:01 non-fungible-nelson

besu besu copied to clipboard

Full sync error on new rpcnode

besu
besu copied to clipboard