跟踪数据库查询时间 - Bookshelf/knex

我想监控 API 数据库查询所花费的时间。我因此使用书架信号（一个书架插件）创建了以下函数。 :

bookshelf.on('fetching', () => {
  server.app.fetching = new Date().valueOf();
});

bookshelf.on('counting', () => {
  server.app.fetching = new Date().valueOf(); 
});

bookshelf.on('fetched', () => {
  server.statsd.gauge('db_query', new Date().valueOf() - server.app.fetching);
});

...这样我就可以检索获取/计数之前和之后的时间；我对删除-删除和保存-保存也做了同样的事情。

我认为我无法理解的是何时应该触发 fetching 和 fetched...当我试图查看何时触发 fetching 和 fetched 时，基本上结果是这样的：

'fetching event A'
'fetching event B'
'fetching event C'
'fetched event C'
'fetched event B'
'fetched event A'

导致计时器明显返回错误值，您有任何线索/线索吗？

我还看到可以在 Knex 上触发“查询”事件，并考虑使用它作为替代解决方案。但是，似乎只有当我指定查询的表时它才有效，即：

 knex('whatever_table').on('query', () => {///});

在我想在每个模型上应用事件处理程序的情况下，这变得不切实际...... 我想我应该坚持使用 Bookshelf，但是我该如何处理事件的处理方式呢？

先感谢您！

我刚刚编写了一些小测试代码，如何使用 knex 跟踪事务持续时间。

https://runkit.com/embed/679qu91ylu4w

/**
 * Calculate transaction durations in knex
 * 
 */
require('sqlite3');
var knex = require("knex")({
  client: 'sqlite', 
  connection: ':memory:', 
  pool: { min: 1, max: 10 }
});

function isTransactionStart(querySpec) {
  return querySpec.sql === 'BEGIN;';
}

function isTransactionEnd(querySpec) {
  return querySpec.sql === 'COMMIT;' || querySpec.sql === 'ROLLBACK;';
}

const transactionDurations = {};

knex.on('query', querySpec => {
  console.log('On query', querySpec);

  if (isTransactionStart(querySpec)) {
    if (transactionDurations[querySpec.__knexUid]) {
      console.error('New transaction started, before earlier was ended');
      return;
    }
    transactionDurations[querySpec.__knexUid] = new Date().getTime();
  }

  if (isTransactionEnd(querySpec)) {
    const startTime = transactionDurations[querySpec.__knexUid];
    if (!startTime) {
      console.error('Transaction end detected, but start time not found');
    }
    const endTime = new Date().getTime();
    transactionDurations[querySpec.__knexUid] = null;
    console.log('TRANSACTION DURATION', endTime - startTime);
  }
}); 

// just as an example of other available events to show when they are called
knex.on('query-response', (res, querySpec) => {
  // console.log('On query response', res, querySpec);
}); 

knex.on('query-error', (err, querySpec) => {
  // console.log('On query error', err, querySpec);
}); 

try {
    a = await Promise.all([
      knex.transaction(trx => {
        return trx.raw('select 1');
      }),
      knex.transaction(trx => {
        return trx.raw('select 2');
      }),
      knex.transaction(trx => {
        return trx.raw('error me');
      })
    ]);
} catch (e) {
  console.log('Got ERROR:', e);
}

同样的方法也应该适用于查询计时。为了防止计时器簿记泄漏内存，您应该添加一些清理代码。

查询持续时间计时器应启动于query事件并停在query-response or query-error取决于哪个先触发。

为了能够匹配query - query-response pair querySpec.__knexQueryUid可以使用属性。