NetWorker：ddboost_precert.logを使用してIOパフォーマンスを分析する方法

NetWorkerクライアント ダイレクト バックアップの問題がクライアントのData Domain DD Boostライブラリーに関連していると考えられる場合のトラブルシューティング手順として、事前認証ログが有効になっています。このユーティリティーは、収集された事前認証ログからパフォーマンスの問題を特定できます。

ddboost_precert.logの準備

簡単な使用例として、 ddboost_precert.ini UNIXのroot、またはWindowsの場合はC:\のroot。ファイルには次のものが含まれている必要があります。 

precert_enabled=true
num_log_files=4
log_file_size=512
log_file_name=ddboost_precert.log
log_file_dir=/DDLog
ddcl_logs_include=true
ddcl_logs_sev=4

記事「Data Domain: 事前認証ツール(事前認証ログ)を使用してDD Boost APIログを有効にして、事前認証ログを収集するための完全な手順の詳細を確認します。(この記事を表示するには、Dellサポートへのログインが必要です)

スクリプトを使用するための準備

前提条件：  Python 3.x
スクリプトを以下からダウンロードします。ソリューション:NetWorkerツール(dell.com)

1. スクリプトを事前認証ログを含む作業ディレクトリーに次のように保存します。 precert-io-perf.pyです。
2. UNIXシステムでは、スクリプトが実行可能であることを確認します。 chmod +x precert-io-perf.py
3. 次のスクリプトを実行します。 ./precert-io-perf.py

入力プロンプト

• プロンプトが表示されたら、DD Boost事前認証ログ ファイルへのフル パスを入力します。
• 各I/O操作にMB/秒を含めるかどうかを尋ねるプロンプトが表示されたら 、[Yes ]または [No ]を入力します

出力サマリー

スクリプトは、次のような書き込みおよび読み取り操作の概要を表示します。

• 書き込みおよび読み取り操作の合計数
• 書き込みおよび読み取りの平均時間
• 書き込みおよび読み取りの最大および最小期間と、それぞれのスループット(MB/秒)
• 書き込みおよび読み取り時間の合計
• 書き込みと読み取りに費やされる合計時間の割合
• 平均書き込みおよび読み取りスループット(MB/秒)
• 詳細なI/O操作

このスクリプトは、詳細なI/O操作を2つのCSVファイルに記録します。

• io-write.log - 各書き込み操作に関する詳細情報が含まれる
• io-read.log - 各読み取り操作に関する詳細情報が含まれています。

出力例

# ./precert-io-perf.py
Please enter the full path to the log file: ./ddboost_precert.log
Do you want to include MB/s for each I/O operation? (yes/no): yes

    Write Durations Summary:

    Total number of write operations: 2646
    Average write duration: 0.005389 seconds
    Maximum write duration: 0.041000 seconds (24.39 MB/s)
    Minimum write duration: 0.000001 seconds (1000000.00 MB/s)
    Total write time: 14.259001 seconds
    Percentage of total time spent on writes: 22.99%
    Average write throughput: 185.566997 MB/s
    Note: High MB/s values for very short durations indicate efficient operations.


    Read Durations Summary:

    Total number of read operations: 2651
    Average read duration: 0.018018 seconds
    Maximum read duration: 31.838000 seconds (0.03 MB/s)
    Minimum read duration: 0.001000 seconds (1000.00 MB/s)
    Total read time: 47.766000 seconds
    Percentage of total time spent on reads: 77.01%
    Average read throughput: 55.499728 MB/s
    Note: High MB/s values for very short durations indicate efficient operations.


Top 20 Slowest Write I/O Operations:
1. I/O Number: 608, Duration: 0.041000 seconds, MB/s: 24.39
2. I/O Number: 2276, Duration: 0.038000 seconds, MB/s: 26.32
3. I/O Number: 1548, Duration: 0.025000 seconds, MB/s: 40.00
4. I/O Number: 2076, Duration: 0.025000 seconds, MB/s: 40.00
5. I/O Number: 636, Duration: 0.024000 seconds, MB/s: 41.67
6. I/O Number: 195, Duration: 0.023000 seconds, MB/s: 43.48
7. I/O Number: 892, Duration: 0.023000 seconds, MB/s: 43.48
8. I/O Number: 1451, Duration: 0.023000 seconds, MB/s: 43.48
9. I/O Number: 1539, Duration: 0.023000 seconds, MB/s: 43.48
10. I/O Number: 1987, Duration: 0.023000 seconds, MB/s: 43.48
11. I/O Number: 604, Duration: 0.022000 seconds, MB/s: 45.45
12. I/O Number: 896, Duration: 0.022000 seconds, MB/s: 45.45
13. I/O Number: 1283, Duration: 0.022000 seconds, MB/s: 45.45
14. I/O Number: 1532, Duration: 0.022000 seconds, MB/s: 45.45
15. I/O Number: 1544, Duration: 0.022000 seconds, MB/s: 45.45
16. I/O Number: 595, Duration: 0.021000 seconds, MB/s: 47.62
17. I/O Number: 616, Duration: 0.021000 seconds, MB/s: 47.62
18. I/O Number: 652, Duration: 0.021000 seconds, MB/s: 47.62
19. I/O Number: 1512, Duration: 0.021000 seconds, MB/s: 47.62
20. I/O Number: 1552, Duration: 0.021000 seconds, MB/s: 47.62

Top 20 Slowest Read I/O Operations:
1. I/O Number: 5900, Duration: 31.838000 seconds, MB/s: 0.03
2. I/O Number: 1614, Duration: 0.041000 seconds, MB/s: 24.39
3. I/O Number: 4950, Duration: 0.039000 seconds, MB/s: 25.64
4. I/O Number: 4550, Duration: 0.026000 seconds, MB/s: 38.46
5. I/O Number: 1670, Duration: 0.025000 seconds, MB/s: 40.00
6. I/O Number: 3494, Duration: 0.025000 seconds, MB/s: 40.00
7. I/O Number: 788, Duration: 0.023000 seconds, MB/s: 43.48
8. I/O Number: 1606, Duration: 0.023000 seconds, MB/s: 43.48
9. I/O Number: 2182, Duration: 0.023000 seconds, MB/s: 43.48
10. I/O Number: 3300, Duration: 0.023000 seconds, MB/s: 43.48
11. I/O Number: 3476, Duration: 0.023000 seconds, MB/s: 43.48
12. I/O Number: 3486, Duration: 0.023000 seconds, MB/s: 43.48
13. I/O Number: 4372, Duration: 0.023000 seconds, MB/s: 43.48
14. I/O Number: 2190, Duration: 0.022000 seconds, MB/s: 45.45
15. I/O Number: 2964, Duration: 0.022000 seconds, MB/s: 45.45
16. I/O Number: 3422, Duration: 0.022000 seconds, MB/s: 45.45
17. I/O Number: 3462, Duration: 0.022000 seconds, MB/s: 45.45
18. I/O Number: 4628, Duration: 0.022000 seconds, MB/s: 45.45
19. I/O Number: 5548, Duration: 0.022000 seconds, MB/s: 45.45
20. I/O Number: 1588, Duration: 0.021000 seconds, MB/s: 47.62
Detailed I/O operations have been logged to io-write.log and io-read.log


example of above analysis:

Percentage of total time spent on writes: 22.99%
Percentage of total time spent on reads: 77.01%

この例では、書き込み操作ではなく、データの読み取り操作でパフォーマンスの問題がどのように発生するかを示します。