Как измерить время выполнения кода javascript с обратными вызовами

Question

У меня есть часть кода javascript, которую я выполняю с помощью интерпретатора node.js.

for(var i = 1; i < LIMIT; i++){ 
    db.users.save({id : i, name : "MongoUser [" + i + "]"}, function(err, saved) { 
      if(err || !saved) console.log("Error"); 
      else console.log("Saved"); 
    }); 
} 

Я хочу знать, как измерить время, затрачиваемое этими операциями вставки db. Я мог бы вычислить разницу значений Date после и до этой части кода, но это было бы неверно из-за асинхронного характера кода.

Answer 1

Используйте Node.js console.time() и console.timeEnd():

var i; 
console.time("dbsave"); 

for(i = 1; i < LIMIT; i++){ 
    db.users.save({id : i, name : "MongoUser [" + i + "]"}, end); 
} 

end = function(err, saved) { 
    console.log((err || !saved)?"Error":"Saved"); 
    if(--i === 1){console.timeEnd("dbsave");} 
}; 

Answer 2

Вы можете дать Benchmark.js попытку. Он поддерживает множество платформ среди них также node.js.

Answer 3

var start = +new Date(); 
var counter = 0; 
for(var i = 1; i < LIMIT; i++){ 
    ++counter; 
    db.users.save({id : i, name : "MongoUser [" + i + "]"}, function(err, saved) { 
      if(err || !saved) console.log("Error"); 
      else console.log("Saved"); 
      if (--counter === 0) 
      { 
       var end = +new Date(); 
       console.log("all users saved in " + (end-start) + " milliseconds"); 
      } 
    }); 
} 

Answer 4

Я рекомендую попробовать NodeTime, который, кажется, хорошо подходит для того, что вы пытаетесь сделать.

Answer 5

Существует метод, который предназначен для этого. Выезд process.hrtime();.

Итак, я в основном ставил это в верхней части моего приложения.

var start = process.hrtime(); 

var elapsed_time = function(note){ 
    var precision = 3; // 3 decimal places 
    var elapsed = process.hrtime(start)[1]/1000000; // divide by a million to get nano to milli 
    console.log(process.hrtime(start)[0] + " s, " + elapsed.toFixed(precision) + " ms - " + note); // print message + time 
    start = process.hrtime(); // reset the timer 
} 

Затем я использую его, чтобы узнать, как долго выполняются функции. Вот простой пример, который печатает содержимое текстового файла с именем «output.txt»:

var debug = true; 
http.createServer(function(request, response) { 

    if(debug) console.log("----------------------------------"); 
    if(debug) elapsed_time("recieved request"); 

    var send_html = function(err, contents) { 
     if(debug) elapsed_time("start send_html()"); 
     response.writeHead(200, {'Content-Type': 'text/html' }); 
     response.end(contents); 
     if(debug) elapsed_time("end send_html()"); 
    } 

    if(debug) elapsed_time("start readFile()"); 
    fs.readFile('output.txt', send_html); 
    if(debug) elapsed_time("end readFile()"); 

}).listen(8080); 

Вот быстрый тест, который вы можете запустить в терминале (BASH оболочки):

for i in {1..100}; do echo $i; curl http://localhost:8080/; done 

Answer 6

console.time('label'), ссылающегося будет записывать текущее время в миллисекундах, а потом вызывая console.timeEnd('label') воли ди вывести продолжительность с этой точки.

Время в миллисекундах будет автоматически печататься вместе с этикеткой, так что вам не придется делать отдельный вызов console.log для печати этикетки:

console.time('test'); 
//some code 
console.timeEnd('test'); //Prints something like that-> test: 11374.004ms 

Для получения дополнительной информации см Mozilla's developer docs on console.time.

Answer 7

Вы также можете попробовать exectimer. Это дает вам обратную связь, как:

var t = require("exectimer"); 

var myFunction() { 
    var tick = new t.tick("myFunction"); 
    tick.start(); 
    // do some processing and end this tick 
    tick.stop(); 
} 

// Display the results 
console.log(t.timers.myFunction.duration()); // total duration of all ticks 
console.log(t.timers.myFunction.min()); // minimal tick duration 
console.log(t.timers.myFunction.max()); // maximal tick duration 
console.log(t.timers.myFunction.mean()); // mean tick duration 
console.log(t.timers.myFunction.median()); // median tick duration 

[править] Существует еще более простой способ теперь использовать exectimer, потому что теперь он может обернуть код для измерения. Ваш код может быть обернут следующим образом:

var t = require('exectimer'), 
Tick = t.Tick; 

for(var i = 1; i < LIMIT; i++){ 
    Tick.wrap(function saveUsers(done) { 
     db.users.save({id : i, name : "MongoUser [" + i + "]"}, function(err, saved) { 
      if(err || !saved) console.log("Error"); 
      else console.log("Saved"); 
      done(); 
     }); 
    }); 
} 

// Display the results 
console.log(t.timers.myFunction.duration()); // total duration of all ticks 
console.log(t.timers.saveUsers.min()); // minimal tick duration 
console.log(t.timers.saveUsers.max()); // maximal tick duration 
console.log(t.timers.saveUsers.mean()); // mean tick duration 
console.log(t.timers.saveUsers.median()); // median tick duration 

Answer 8

Старый вопрос, но для простого API и легкого решения; вы можете использовать perfy, который использует высокое разрешение в реальном времени (process.hrtime) внутри.

var perfy = require('perfy'); 

function end(label) { 
    return function (err, saved) { 
     console.log(err ? 'Error' : 'Saved'); 
     console.log(perfy.end(label).time); // <——— result: seconds.milliseconds 
    }; 
} 

for (var i = 1; i < LIMIT; i++) { 
    var label = 'db-save-' + i; 
    perfy.start(label); // <——— start and mark time 
    db.users.save({ id: i, name: 'MongoUser [' + i + ']' }, end(label)); 
} 

Обратите внимание, что каждый раз, когда perfy.end(label) называется, что экземпляр автоматически уничтожается.

Disclosure: Написал этот модуль, вдохновленный D.Deriso's answer. Документы here.

Answer 9

И еще один вариант заключается в использовании express-debug инструмента:

экспресс-отладка является инструментом разработки для экспресса. Это простое промежуточное программное обеспечение, которое вводит полезный отладочный вывод в ваш html без препятствий.

Это удобно предлагает профилирование панель:

общее время обработки REQ. промежуточное программное обеспечение, параметры и сроки маршрута.

Также. чтобы добавить к приведенным выше ответам, вы можете проверить this answer, чтобы включить только код профилирования для среды разработки.

Answer 10

Для тех, кто хочет получить время, истекшее значение вместо консольного вывода:

использование process.hrtime() как @ D.Deriso предложение, ниже мой простой подход:

function functionWantToMeasure() { 
    var startTime = process.hrtime(); 
    //do some task... 
    var elapsedSeconds = parseHrtimeToSeconds(process.hrtime(startTime)); 
    console.log('functionWantToMeasure takes ' + elapsedSeconds + 'seconds'); 
} 

function parseHrtimeToSeconds(hrtime) { 
    var seconds = (hrtime[0] + (hrtime[1]/1e9)).toFixed(3); 
    return seconds; 
} 

Answer 11

У меня было то же самое при переходе от AWS к Azure

Для экспресс & aws, вы уже можете использовать существующее время() и t imeEnd()

Для Azure, используйте: https://github.com/manoharreddyporeddy/my-nodejs-notes/blob/master/performance_timers_helper_nodejs_azure_aws.js

Это время() и timeEnd() использовать функцию существующей hrtime(), которые дают с высоким разрешением в режиме реального времени.

Надеюсь, это поможет.