Bagaimana cara mengukur waktu eksekusi kode JavaScript dengan panggilan balik?

Saya memiliki kode JavaScript yang saya jalankan menggunakan node.jsinterpreter.

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

Bagaimana saya bisa mengukur waktu yang dibutuhkan oleh operasi penyisipan basis data ini? Saya dapat menghitung perbedaan nilai tanggal setelah dan sebelum potongan kode ini tetapi itu tidak benar karena sifat asinkron dari kode.

Gunakan Node.js console.time()dan 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");}
};

Ada metode yang dirancang untuk ini. Periksa process.hrtime (); .

Jadi, pada dasarnya saya menempatkan ini di bagian atas aplikasi saya.

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
}

Lalu saya menggunakannya untuk melihat berapa lama waktu yang dibutuhkan. Berikut adalah contoh dasar yang mencetak konten file teks yang disebut "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);

Inilah tes cepat yang dapat Anda jalankan di terminal (BASH shell):

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

Memanggil console.time('label')akan merekam waktu saat ini dalam milidetik, kemudian panggilan console.timeEnd('label')akan menampilkan durasi dari titik itu.

Waktu dalam milidetik akan dicetak secara otomatis di samping label, jadi Anda tidak perlu membuat panggilan terpisah ke console.log untuk mencetak label:

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

Untuk informasi lebih lanjut, lihat dokumen pengembang Mozilla diconsole.time .

Terkejut belum ada yang menyebutkan perpustakaan yang baru dibangun:

Tersedia di Node> = 8.5, dan harus di Browser Modern

https://developer.mozilla.org/en-US/docs/Web/API/Performance

https://nodejs.org/docs/latest-v8.x/api/perf_hooks.html#

Node 8.5 ~ 9.x (Firefox, Chrome)

// const { performance } = require('perf_hooks'); // enable for node
const delay = time => new Promise(res=>setTimeout(res,time))
async function doSomeLongRunningProcess(){
  await delay(1000);
}
performance.mark('A');
(async ()=>{
  await doSomeLongRunningProcess();
  performance.mark('B');
  performance.measure('A to B', 'A', 'B');
  const measure = performance.getEntriesByName('A to B')[0];
  // firefox appears to only show second precision.
  console.log(measure.duration);
  performance.clearMeasures(); // apparently you should remove entries...
  // Prints the number of milliseconds between Mark 'A' and Mark 'B'
})();

https://repl.it/@CodyGeisler/NodeJsPerformanceHooks

Node 10.x

https://nodejs.org/docs/latest-v10.x/api/perf_hooks.html

const { PerformanceObserver, performance } = require('perf_hooks');
const delay = time => new Promise(res => setTimeout(res, time))
async function doSomeLongRunningProcess() {
    await delay(1000);
}
const obs = new PerformanceObserver((items) => {
    console.log('PerformanceObserver A to B',items.getEntries()[0].duration);
    performance.clearMarks();
});
obs.observe({ entryTypes: ['measure'] });

performance.mark('A');

(async function main(){
    try{
        await performance.timerify(doSomeLongRunningProcess)();
        performance.mark('B');
        performance.measure('A to B', 'A', 'B');
    }catch(e){
        console.log('main() error',e);
    }
})();

Bagi siapa pun yang ingin mendapatkan nilai waktu yang berlalu alih-alih output konsol:

gunakan process.hrtime () sebagai saran @ D.Deriso, di bawah ini adalah pendekatan saya yang lebih sederhana:

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

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

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");
          }
    });
}

Pertanyaan lama tetapi untuk API sederhana dan solusi ringan; Anda dapat menggunakan perfy yang menggunakan real time resolusi tinggi ( process.hrtime) secara internal.

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));
}

Perhatikan bahwa setiap kali perfy.end(label)dipanggil, instance itu dihancurkan secara otomatis.

Pengungkapan: Menulis modul ini, terinspirasi oleh jawaban D.Deriso . Dokumen di sini .

Anda bisa mencoba Benchmark.js . Ini mendukung banyak platform di antara mereka juga node.js.

Anda juga dapat mencoba exectimer . Ini memberi Anda umpan balik seperti:

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

[Sunting] Bahkan ada cara yang lebih sederhana sekarang untuk menggunakan exectimer karena sekarang dapat membungkus kode yang akan diukur. Kode Anda dapat dibungkus seperti ini:

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

Saya memiliki masalah yang sama saat pindah dari AWS ke Azure

Untuk mengekspresikan & aws, Anda sudah dapat menggunakan, waktu yang ada () dan timeEnd ()

Untuk Azure, gunakan ini: https://github.com/manoharreddyporeddy/my-nodejs-notes/blob/master/performance_timers_helper_nodejs_azure_aws.js

Time () dan timeEnd () ini menggunakan fungsi hrtime () yang ada, yang memberikan real time resolusi tinggi.

Semoga ini membantu.

Dan opsi lain adalah menggunakan alat debug-cepat :

express-debug adalah alat pengembangan untuk express. Ini middleware sederhana yang menyuntikkan output debug yang berguna ke html Anda, dengan cara yang tidak menghalangi.

Dengan mudah menawarkan panel profil:

total waktu pemrosesan req. middleware, param, dan timing rute.

Juga. untuk menambahkan jawaban di atas, Anda dapat memeriksa jawaban ini untuk mengaktifkan kode profil apa pun hanya untuk lingkungan pengembangan.