<3615 mylife> Bon… Je me suis un peu foiré sur ce coup
Déjà, je me suis trouvé un vrai boulot qui m’a bouffé du temps. Surtout j’ai sous estimé la complexité de gérer 2 notions (temps CPU et temps réel) dans 3 contextes (mono-thread, muti-thread, multi-process. Et meme multi-thread dans un multi-process)
Alors bon… j’ai revu mon ambitions à la baisse et je vous présente donc: </3615 mylife>
La librairie zzChrono
Les sources sont là
Le but
Cet outils répond à un problème simple: combien de temps mon programme a t-il
passé dans telle ou telle portion du code.
Il se veux facile d’utilisation et non-verbeux. Si vous cherchez le chrono le plus précis de l’univers, passez votre chemin (ou mieux: améliorez mon travail)
Il diffère de timeit qui est plutôt un benchmark pour un code isolé.
Il est un peu redondant avec les profiler mais en VACHEMENT plus simple
Limitation
Ce module donne des résultat souvent fantaisiste dans un scenario multi-thread.
Cela fonctionne dans un scenario multi-processus, mais avec une instance dans chaque processus.
Les temps totaux sont significatifs à environs 0.1s. Dans la mesure oũ cet outils sert à mesurer l’impact total d’une portion dans la durée de vie d’un programme, ce n’est pas gênant. Mais ça peut le devenir dans un autre contexte.
Le décorateur @zz_chrono.chronometer(nom)
C’est la façon la plus simple d’utiliser cette librairie
Il suffit d’ajouter ce décorateur à la fonction qu’on souhaite surveiller
Exemple simple:
from zz_chrono import *
@chronometer("initialisation")
def initialisation():
(...)
@chronometer("Faire un truc")
def do_something():
(...)
@chronometer("un autre truc")
def do_something_else():
(...)
initialisation()
for i in range (1000):
if some_condition():
do_something()
else:
do_something_else()
display_all()
La dernière ligne affiche le compte-rendu suivant
<Chrono "initialisation".elapsed time total:0.09115540896728355s >
<Chrono "initialisation".CPU time total:0.09375s >
<Chrono "Faire un truc".elapsed time total:1.947161044425888s >
<Chrono "Faire un truc".CPU time total:1.90625s >
<Chrono "un autre truc".elapsed time total:0.03172837835946546s >
<Chrono "un autre truc".CPU time total:0.046875s >
Ceci nous apprend donc que la fonction “initialisation” s’est exécutée en 0.098 seconde en
temps réel et pendant 0.09375 seconde en temps CPU
Sautez à ce chapitre pour mieux comprendre ces différents temps
On comprend donc que l’essentiel du temps d’exécution est concentré dans “do_something()”
Regrouper les chronos
Il se peut aussi qu’on veuillent regrouper plusieurs fonctions dans un chrono “fourre-tout”.
Pas de problème. Il suffit de leur donner le même nom de chrono.
Dans notre précédent exemple, mettons qu’on souhaite exploser l’initialisation en 3 parties
@zz_timer.timer("initialisation")
def init1()
(...)
@zz_timer.timer("initialisation")
def init2()
(...)
@zz_timer.timer("initialisation")
def init3()
(...)
(...)
Le chrono nommé “initialisation” représentera donc le cumul des 3 fonctions
<Chrono "Faire un truc".elapsed time total:1.9543509192608595s >
<Chrono "Faire un truc".CPU time total:1.859375s >
<Chrono "initialisation".elapsed time total:0.14157410301506365s >
<Chrono "initialisation".CPU time total:0.140625s >
<Chrono "un autre truc".elapsed time total:0.031408351488404734s >
<Chrono "un autre truc".CPU time total:0.0625s >
On peut aussi récupérer le chrono par son nom
>>>mon_chrono = getChrono("initialisation")
>>>print (mon_chrono.chrono_list[REAL_TIME])
>>>elapsed time total:0.14157410301506365s
Chronométrer un bout de code
Il arrive aussi qu’on veuille chronométrer un bout de code flottant.
Cela se fait en utilisant la clause “with” sur un object chronometre
exemple
while some_condition():
(...)
with getChrono("pygame"):
pygame.manage_events()
pygame.display(screen)
print (getChrono("pygame"))
On mesure quoi, au fait?
Mesurer le temps, c’est un problème tout bête dans les grandes ligne, mais super compliqué quand on s’inquiète des détails.
Ma librairie utilise 2 notions de temps:
- le temps écoulé (alias « temps réel »)
- le temps CPU
En revanche, il existe principalement 3 notions différentes de temps pour l’ordinateur:
L’horloge:
C’est celle que tout le monde connaît. Elle indique l’heure et on peut en déduire le temps écoulé.
Le problème est qu’on peut la modifier (à raison si on voyage ou passe à l’heure d’hiver). Elle n’est donc pas très fiable.
Si vous utilisez une version antérieure à Python 3.3, c’est cette horloge qui donne le “temps réel”
Le compteur interne:
C’est une horloge qui donne le temps écoulé depuis l’allumage de l’ordinateur.
Elle ne peut pas être modifiée est s’écoule régulièrement (horloge monotone).
Elle est donc plus fiable pour un intervalle de temps.
Si vous utilisez une version égale ou postérieure à Python 3.3, c’est cette horloge qui donne le “temps réel”
Le temps CPU
C’est le temps passée par un cœur du CPU à traiter un processus.
l’horloge plus en détail
Cette horloge indique l’heure. Intervalle entre l’heure T1 et l’heure T2 se calcule comme T2-T1.
Problème: elle peut être modifiée par l’utilisateur ou le système et s’écoule parfois selon des règle peu instinctive (rattrapage des secondes puisqu’une année ne dure pas exactement 365.25 jours)
T2-T1 ne donne donc pas toujours le résultat attendu
Si vous utilisez une vieille version de Python (<3.3), c’est cette horloge qui donne le “temps réel”
Elle s’obtient par la commande [link]time.time()
Le compteur interne:
C’est une horloge qui donne le temps écoulé depuis l’allumage de l’ordinateur.
Il ne donne donc pas l’heure mais peut calculer un intervalle de temps.
Il ne peut pas être modifié est s’écoule régulièrement (horloge monotone).
Son comportement pendant une mise en pause dépend de l’implémentation système et matérielle.
Elle a, a priori, une meilleur precision que l’horloge système.
Si vous utilisez une version récente de Python (3.3 ou plus), c’est cette horloge qui donne le “temps réel”
Il s’obtient par la commande [link]time.perf_counter()
Le temps CPU plus en détail
C’est le temps passée par un cœur du CPU à traiter un processus.
Attention: cela ne correspond pas au temps de traitement. Par exemple, les accès au disque ou à la carte graphique font partit du temps de traitement mais ne sont pas inclus dans le temps CPU
Plusieur threads peuvent exister dans un processus. L’horloge CPU donne l’integralité du temps CPU ecoulé dans un processus. Ce module donne donc des résultat parfois “bizarre” dans un scénario multithread. Je corigerais cela… un jour…
Ce chronomètre ne s’écoule pas quand l’ordinateur est en pause.
Il s’obtient par la commande [link]time.process_time()
le temps écoulé plus en détail
Ce temps mesure, à chaque fois, intervalle entre l’entrée dans la fonction et la sortie de la fonction (ou du bout de code).
En conséquence:
- Si un autre programme/processus effectue un traitement lourd en tache de fond, ce temps sera plus long
- Si 2 threads utilisant le meme chrono se chevauchent, on obtient n’importe quoi (je sais: c’est facile à régler mais comme je n’ai pas pus obtenir un module completement thread-friendly, j’ai fait un code simple et mono-thread)
Pour finir, voici les liens:
– Le module zzChrono.py
– La documentation (en anglais, aproximatif, pour l’instant)
– Donation à votre bienfaiteur
