test: debug transaction leak (#12153)

lib/hooks.js

@@ -33,6 +33,7 @@ const hookTypes = {
   beforeFindAfterOptions: { params: 1 },
   afterFind: { params: 2 },
   beforeCount: { params: 1 },
+  transactionCreated: { params: 1, sync: true, noModel: true },
   beforeDefine: { params: 2, sync: true, noModel: true },
   afterDefine: { params: 1, sync: true, noModel: true },
   beforeInit: { params: 2, sync: true, noModel: true },

lib/sequelize.js

@@ -644,11 +644,11 @@ class Sequelize {
       const query = new this.dialect.Query(connection, this, options);
 
       try {
-        await this.runHooks('beforeQuery', options, query);
-        await checkTransaction();
+        await this.runHooks('beforeQuery', options, query, sql);
+        checkTransaction();
         return await query.run(sql, bindParameters);
       } finally {
-        await this.runHooks('afterQuery', options, query);
+        await this.runHooks('afterQuery', options, query, sql);
         if (!options.transaction) {
           await this.connectionManager.releaseConnection(connection);
         }
@@ -1100,6 +1100,7 @@ class Sequelize {
     }
 
     const transaction = new Transaction(this, options);
+    this.runHooks('transactionCreated', transaction);
 
     if (!autoCallback) {
       await transaction.prepareEnvironment(false);

test/integration/support.js

@@ -2,14 +2,29 @@
 
 const Support = require('../support');
 
-const runningQueries = new Set();
+const runningQueries = new Map();
+const runningTransactions = new Map(); // map transaction option to queries.
 
 before(function() {
-  this.sequelize.addHook('beforeQuery', (options, query) => {
-    runningQueries.add(query);
+  this.sequelize.addHook('transactionCreated', t => { // tracking race condition, remove me if no longer present.
+    t.trace = new Error().stack;
   });
-  this.sequelize.addHook('afterQuery', (options, query) => {
+  this.sequelize.addHook('beforeQuery', (options, query, sql) => {
+    runningQueries.set(query, options);
+    if (options.transaction) {
+      const queryList = runningTransactions.get(options.transaction.id);
+      if (queryList) {
+        queryList.push(sql);
+      } else {
+        runningTransactions.set(options.transaction.id, [sql]);
+      }
+    }
+  });
+  this.sequelize.addHook('afterQuery', (options, query, sql) => {
     runningQueries.delete(query);
+    if (options.transaction && ['COMMIT', 'ROLLBACK'].includes(sql)) {
+      runningTransactions.delete(options.transaction.id);
+    }
   });
 });
 
@@ -22,7 +37,18 @@ afterEach(function() {
     return;
   }
   let msg = `Expected 0 running queries. ${runningQueries.size} queries still running in ${this.currentTest.fullTitle()}\n`;
-  msg += `Queries:\n${[...runningQueries].map(query => `${query.uuid}: ${query.sql}`).join('\n')}`;
+  msg += 'Queries:\n\n';
+  for (const [query, options] of runningQueries) {
+    msg += `${query.uuid}: ${query.sql}\n`;
+    if (options.transaction) {
+      const relatedTransaction = runningTransactions.get(options.transaction.id);
+      if (relatedTransaction) {
+        msg += options.transaction.trace;
+        msg += 'In transaction:\n\n';
+        msg += relatedTransaction.join('\n');
+      }
+    }
+  }
   throw new Error(msg);
 });