NetWorker : Comment analyser les performances des E/S à l’aide de ddboost_precert.log

La journalisation avant certification est activée en tant qu’étape de dépannage lorsque les problèmes de sauvegarde NetWorker Client Direct sont considérés comme liés à la bibliothèque Data Domain DD Boost du client. Cet utilitaire peut identifier les problèmes de performances à partir des logs de précert collectés.

Préparation ddboost_precert.log

À titre d’exemple d’utilisation simple, créez ddboost_precert.ini sur la racine UNIX ou la racine de C :\ pour Windows. Le fichier doit contenir : 

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

Voir l’article Data Domain : Activez la journalisation de l’API DD Boost avec l’outil precert (journaux precert) pour obtenir des détails complets sur les procédures de collecte des journaux precert. (vous devez vous connecter au support Dell pour afficher cet article)

Préparation à l’utilisation du script

Conditions préalables :  Python 3.x
Téléchargez le script à partir de : Solutions NetWorker Tools (dell.com)

1. Enregistrez le script dans un répertoire de travail contenant les logs precert en tant que precert-io-perf.py.
2. Sur les systèmes UNIX, assurez-vous que le script est exécutable : chmod +x precert-io-perf.py
3. Exécutez le script : ./precert-io-perf.py

Invites d’entrée

• Lorsque vous y êtes invité, saisissez le chemin d’accès complet au fichier log de précert DD Boost.
• Saisissez Oui ou Non lorsque vous êtes invité à indiquer si vous souhaitez inclure Mo/s pour chaque opération d’E/S

Récapitulatif de la sortie

Le script affiche un récapitulatif des opérations d’écriture et de lecture, notamment :

• Nombre total d’opérations d’écriture et de lecture
• Durée moyenne d’écriture et de lecture
• Durée maximale et minimale d’écriture et de lecture, ainsi que leur débit respectif (Mo/s)
• Temps total d’écriture et de lecture
• Pourcentage du temps total consacré à l’écriture et à la lecture
• Débit moyen d’écriture et de lecture (Mo/s)
• Opérations d’E/S détaillées

Le script consigne les opérations d’E/S détaillées dans deux fichiers CSV :

• io-write.log : contient des informations détaillées sur chaque opération d’écriture
• io-read.log : contient des informations détaillées sur chaque opération de lecture

Exemple de sortie

# ./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%

Cet exemple montre comment le problème de performances se manifeste avec l’opération de lecture des données par opposition aux opérations d’écriture.