Почему не отображается время работы функции при использовании datetime.now()?

Question

[natalya1000]

Нижеприведенный код по задумке должен выдавать время, которое отработала функция.
Т.е. мы создали декоратор, который выдает время функции. После навешивания декоратора на функцию - должно выдавать время работы функции.
Например: 0:00:12. Но по какой-то причине у одного человека этот код работает, а у меня - нет.

У меня всегда выводится результат по нулям: 0:00:00 - время работы функции. Не понимаю, что сделано не так, хотя вроде все должно отрабатывать.

from typing import Callable
from datetime import datetime

def deco_time(func: Callable) -> Callable:
    def inner(*args, **kwargs):
        start = datetime.now()
        result = func(*args, **kwargs)
        finish = datetime.now()
        print(f'Время работы функции: {finish - start}')
        return result
    return inner

@deco_time
def summa(a, b):
    return a + b

print(summa(5000000000, 100000000))

Comments

[Everything_is_bad]

показывай полный вывод, один в один

Answer 1

[dim5x]

natalya1000, Сделайте чуть более нагруженную функцию. То что у вас сейчас считается за столь незначительное время, что у вас всегда будет по нулям.

Ну и, если вам непременно нужно подсчитывать столь незначительные промежутки, используйте
perf_counter() из модуля time.

Comments

[Everything_is_bad]

форматирование разницы дат нет, там всё равно должно вывести долю после запятой

[dim5x]

Everything_is_bad, не должно. Попробуйте сами. Сделайте что-то более долгое, в цикле, например. Будет вам и с этой реализацией нормальный результат. Но подсчёт таких вещей делают, имхо, не с datetime, а c time.

[Everything_is_bad]

dim5x, да я в курсе что подсчет делается другим, но даже этот код должен вывести что-то типа 0:00:00.000001 а не 0:00:00

[dim5x]

Everything_is_bad, datetime.now() выдаёт минимальное значение в микросекундах, а a + b выполняется за некоторое количество наносекунд. Соответственно для datetime.now() и стартовое и финишное время одинаково, отсюда и нули.

[natalya1000]

dim5x, Everything_is_bad,
у другого человека тот же код выводит время функции в формате - 0:00:00.000001, хотя он также делает через datetime

я сделала функцию "более нагруженной" - сложение нескольких чисел. И я сделала декоратор, который вызывает
функцию 64 раза, т.е. каждый последующий вызов - должен показывать более долгое время

но все равно все по нулям
все 64 раза - за наносекунды, в том числе последний 64-ый раз - это все равно норма?

from typing import Callable
from datetime import datetime


def deco_repeater(count: int):
    def deco_time(func: Callable) -> Callable:
        result = []
        def inner(*args, **kwargs):
            for _ in range(count):
                start = datetime.now()
                result.append(func(*args, **kwargs))
                finish = datetime.now()
                print(f'Время работы функции: {finish - start}')
            return result

        return inner

    return deco_time

@deco_repeater(64)
def summa(a, b, c, d, e, f):
    return a + b + c + d + e + f

summa(1, 4, 5, 6, 7, 8)

Выводит:
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00
Время работы функции: 0:00:00

[natalya1000]

dim5x, Everything_is_bad,
увеличила число вызовов до 10 миллинов, и в последний раз функция отрабатывает все равно за 00:00:00 сек.
такое чувство, что у меня выводится в неправильном формате

[dim5x]

natalya1000, не так.

Вам нужно не 64 раза выполнять одну функцию, а применить цикл внутри измеряемой функции - как вариант её "утяжеления".

Сделайте именно функцию более тяжёлой, например:

@deco_time
def f(a, b):
    c = 0
    for i in range(1000000):
        c += a * b
    return c

>>> Время работы функции: 0:00:00.049027

Арифметическое сложение особенно таких малых чисел очень лёгкая операция (нет разницы между 2 или 6 слагаемыми).

такое чувство, что у меня выводится в неправильном формате

Всё правильно выводится.

Для измерения используют time.
Почему у вас нули я объяснил в комментарии к Everything_is_bad.

То что

у другого человека тот же код выводит время функции в формате - 0:00:00.000001

говорит лишь о том, что то устройство на котором он запускает код работает настолько медленно что datetime.now() успевает отсчитать микросекунду, чтобы разница между стартовым и финишным временем была не нулевой.

[Everything_is_bad]

natalya1000, перестань использовать datetime.now

[natalya1000]

dim5x,
да, проверила, все отрабатывает

это нормально, что каждый раз одна и та же функция, с одними и теми же аргументами отрабатывает за разное время?

Время работы функции: 0:00:00.046875
Время работы функции: 0:00:00.042885
Время работы функции: 0:00:00.043882
Время работы функции: 0:00:00.043587
Время работы функции: 0:00:00.044879

[dim5x]

natalya1000, да. Нагрузка на систему в любой момент времени разнится, небольшие колебания естественны.

[natalya1000]

dim5x,
получается, что по-хорошему, для более простых функций лучше использовать
perf_counter() из модуля time

или метод .total_seconds()

[dim5x]

natalya1000, да.
Вариантов много. Смотреть что использовать в зависимости от измеряемой функции и требуемой точности. Есть и time.time_ns() и time.perf_counter_ns().

Почитайте-посмотрите: https://docs-python.ru/standart-library/modul-time...

[dim5x]

natalya1000, если ответ рабочий - не забудьте отметить решением.