measure time spent in each phase during node start

オープン
  • 何か問題がありますか？
#949 コメント 0 件 リアクション 0 件 担当者 1 名 GitHub で見る

メンテナーはふだん 1 日以内に返信

@dorin100 がすでに取り組んでいます。

2022年7月18日 から。

評価

この issue はまだ評価されていません。

説明

sync-tests

measure the node start time per stage:

  • Chain DB
  • Immutable DB
  • Volatile DB
  • Ledger DB

Scenarios:
1.

  • (have the node fully synced with tagNo1)
  • clean stop --> stop the node using killall -SIGINT cardano-node
  • start the node using the same tagNo1 and measure the time spend in each of the 3 phases + total time until the node is fully started

2.

  • (have the node fully synced with tagNo1)
  • clean stop --> stop the node using killall -SIGINT cardano-node
  • start the node using tagNo2 and measure the time spend in each of the 3 phases + total time until the node is fully started

3.

  • (stop the node during the Immutable DB phase using killall -SIGINT cardano-node)
  • start the node and measure the time spend in each of the 3 phases + total time until the node is fully started

4.

  • (stop the node during the Volatile DB phase using killall -SIGINT cardano-node)
  • start the node and measure the time spend in each of the 3 phases + total time until the node is fully started

5.

  • (stop the node during the Ledger DB phase using killall -SIGINT cardano-node)
  • start the node and measure the time spend in each of the 3 phases + total time until the node is fully started
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:20.85 UTC] Started opening Chain DB
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:20.85 UTC] Started opening Immutable DB
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:20.97 UTC] Validating chunk no. 2504 out of 2504. Progress: 99.96%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:21.09 UTC] Validated chunk no. 2504 out of 2504. Progress: 100.00%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:21.09 UTC] Found a valid last location at chunk 2504 with tip 708c365fdb6c146acc50bfaf8ca59fa34035a8d9c2d0f8a0e170b9f98aa69873@54086732.
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:21.09 UTC] Opened imm db with immutable tip at 708c365fdb6c146acc50bfaf8ca59fa34035a8d9c2d0f8a0e170b9f98aa69873 at slot 54086732 and chunk 2504
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:21.09 UTC] Started opening Volatile DB
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:30.11 UTC] Opened vol db
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:29:30.11 UTC] Started opening Ledger DB
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:32:23.31 UTC] Replaying ledger from snapshot at 140c1227031439a1441951e110059a7fb646ab14609ea2f5a226c26307ac1fed at slot 54082830
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:32:23.37 UTC] Replayed block: slot 54082842 out of 54086732. Progress: 0.31%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:32:31.90 UTC] Replayed block: slot 54086392 out of 54086732. Progress: 91.29%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:32:39.52 UTC] Opened lgr db
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:32:39.52 UTC] Started initial chain selection
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:32:40.07 UTC] Pushing ledger state for block 7d9b7375e2857695278851e0c0a7b87b8ee0dbd45eef7a3605c1b9a66e41ae93 at slot 54086744. Progress: 0.00%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:12.10 UTC] before next, messages elided = 54086745
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:12.10 UTC] Pushing ledger state for block 6f4116ecaf0ff9b1377ab607b8240d437cc9bb6fa3b818b2a41bc4f67c85553c at slot 54096736. Progress: 21.77%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:12.10 UTC] Pushing ledger state for block bec48d38cbb593571aff74dbef17b4779aed56e4a580d03252507c9890df0bf6 at slot 54096747. Progress: 21.80%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:12.17 UTC] Pushing ledger state for block 1d09c4c067286d5572d709b4b83b99eff0bed0a1f2840cd625e7d162027853a9 at slot 54096750. Progress: 21.80%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:44.10 UTC] before next, messages elided = 54096847
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:44.10 UTC] Pushing ledger state for block 0c5d3d0d373f4fbef5eac9a615ef2e9dbe13e4038da0eb325c3f164d897ad4ac at slot 54106832. Progress: 43.77%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:44.10 UTC] Pushing ledger state for block 35c003cebefdc43d0794b59ffc2561b0fd491f3235de7ca05d1053e99b6151a9 at slot 54106864. Progress: 43.84%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:33:44.17 UTC] Pushing ledger state for block 3cd7281deccffb9de2653d0be567062743c52b4fb520ed278c1ac530bc6b4428 at slot 54106866. Progress: 43.84%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:16.47 UTC] before next, messages elided = 54106901
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:16.47 UTC] Pushing ledger state for block 146d1cfa442b8c3f604f365a311434f929d2efead9163332c0a46d8952d7ecbb at slot 54116884. Progress: 65.67%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:16.47 UTC] Pushing ledger state for block 792e880c7c4c52c0b22a3ebcd667923290ab1d402ce0f7ea96f945db64c3c796 at slot 54116908. Progress: 65.72%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:16.54 UTC] Pushing ledger state for block ae770df77e5f7e2841290e2f9d2faa982768ff02abaaaf009d24f426f905b246 at slot 54116923. Progress: 65.76%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:54.25 UTC] before next, messages elided = 54116967
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:54.25 UTC] Pushing ledger state for block 1ef5aac2c084751ca718a5a1f7c416658e6511e4bef5176073e8b24911bdad76 at slot 54126944. Progress: 87.59%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:54.25 UTC] Pushing ledger state for block d657a70e587b57b9e5e923e050367c49f6ca35a0f761a2d2c3473160eda35ba4 at slot 54126973. Progress: 87.65%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:34:54.37 UTC] Pushing ledger state for block ea1158ca93bfb2eaef6d180f947e26cfb68e2d29b5d2bdf0ea96221b0ff8f21b at slot 54126999. Progress: 87.71%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:35:14.18 UTC] before next, messages elided = 54127008
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:35:14.18 UTC] Pushing ledger state for block 7760c1b38ccc7d85cf916a6bc0a7c7f26b3420c5c1e9b08f357c0e460da0f42e at slot 54132639. Progress: 100.00%
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:35:14.18 UTC] Valid candidate at tip 7d9b7375e2857695278851e0c0a7b87b8ee0dbd45eef7a3605c1b9a66e41ae93 at slot 54086744
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:35:14.18 UTC] Initial chain selected
[ip-172-3:cardano.node.ChainDB:Info:5] [2022-03-02 07:35:14.18 UTC] Opened db with immutable tip at 708c365fdb6c146acc50bfaf8ca59fa34035a8d9c2d0f8a0e170b9f98aa69873 at slot 54086732 and tip 7760c1b38ccc7d85cf916a6bc0a7c7f26b3420c5c1e9b08f357c0e460da0f42e at slot 54132639
[ip-172-3:cardano.node.DnsSubscription:Notice:416] [2022-03-02 07:35:14.20 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt Start, destination 3.23.246.240:3001
[ip-172-3:cardano.node.DnsSubscription:Notice:416] [2022-03-02 07:35:14.20 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt End, destination 3.23.246.240:3001 outcome: ConnectSuccess
[ip-172-3:cardano.node.DnsSubscription:Notice:430] [2022-03-02 07:35:14.22 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt Start, destination 18.157.126.47:3001
[ip-172-3:cardano.node.DnsSubscription:Notice:431] [2022-03-02 07:35:14.25 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt Start, destination 18.158.188.21:3001
[ip-172-3:cardano.node.DnsSubscription:Notice:432] [2022-03-02 07:35:14.27 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt Start, destination 3.112.168.85:3001
[ip-172-3:cardano.node.DnsSubscription:Notice:433] [2022-03-02 07:35:14.30 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt Start, destination 3.13.80.218:3001
[ip-172-3:cardano.node.DnsSubscription:Notice:433] [2022-03-02 07:35:14.30 UTC] Domain: "relays-new.cardano-mainnet.iohk.io" Connection Attempt End, destination 3.13.80.218:3001 outcome: ConnectSuccessLast
[ip-172-3:cardano.node.ErrorPolicy:Notice:400] [2022-03-02 07:35:14.30 UTC] IP 18.157.126.47:3001 ErrorPolicySuspendConsumer (Just (ConnectionExceptionTrace (SubscriberError {seType = SubscriberParallelConnectionCancelled, seMessage = "Parallel connection cancelled", seStack = []}))) 1s
[ip-172-3:cardano.node.ErrorPolicy:Notice:400] [2022-03-02 07:35:14.30 UTC] IP 18.158.188.21:3001 ErrorPolicySuspendConsumer (Just (ConnectionExceptionTrace (SubscriberError {seType = SubscriberParallelConnectionCancelled, seMessage = "Parallel connection cancelled", seStack = []}))) 1s
[ip-172-3:cardano.node.ErrorPolicy:Notice:400] [2022-03-02 07:35:14.30 UTC] IP 3.112.168.85:3001 ErrorPolicySuspendConsumer (Just (ConnectionExceptionTrace (SubscriberError {seType = SubscriberParallelConnectionCancelled, seMessage = "Parallel connection cancelled", seStack = []}))) 1s
[ip-172-3:cardano.node.ChainDB:Notice:382] [2022-03-02 07:35:14.44 UTC] Chain extended, new tip: b5a12910959c5b397aa53977269843d91e2a634b769b551633d3e1525d3b0101 at slot 54132765
[ip-172-3:cardano.node.ChainDB:Notice:382] [2022-03-02 07:35:15.77 UTC] Chain extended, new tip: d06ac1d4f18f56eb74f02b6a2028526b0900b97248ed1d3b15fc9cc6cce1efba at slot 54133196
[ip-172-3:cardano.node.ChainDB:Notice:382] [2022-03-02 07:35:17.05 UTC] Chain extended, new tip: e809ee4a5812055f52e902a4611ec79fd9b4cc9ccd6f33f4325fc754a2f9a9bd at slot 54133355
主要言語
Python
スター
59
フォーク
28
平均マージ
8時間 27分
マージ済み PR（30日）
88

環境構築

はじめの一歩

  1. issue を最後まで読み、次にプロジェクトのコントリビューションガイドを読みます。
  2. 着手することを issue にコメントします — 二人が同じ作業をするのを防げます。
  3. リポジトリをフォークし、ブランチを切って変更します。
  4. issue 番号を参照したプルリクエストを送ります。

IntersectMBO/cardano-node-tests のほかの issue

IntersectMBO/cardano-node-tests の issue をすべて見る

似ている issue

Python の issue をもっと見る

新しい issue をメールで受け取る

初心者向けの GitHub issue を短くまとめたダイジェスト。