Merge pull request #7 from daniloisr/handle-async-context

Fix context between two async requests

Author: NickCraver

.travis.yml

@@ -1,7 +1,6 @@
 language: node_js
 node_js:
-  - "4"
-  - "5"
+  - "8"
   - "stable"
 
 sudo: required

lib/async-context.js

@@ -0,0 +1,28 @@
+'use strict';
+
+const asyncHooks = require('async_hooks');
+
+class AsyncContext {
+  constructor() {
+    this.map = new Map();
+    asyncHooks.createHook({
+      init: (id, _type, triggerId) => {
+        if (this.map.has(triggerId))
+          this.map.set(id, this.map.get(triggerId));
+      },
+      destroy: (id) => this.map.delete(id)
+    }).enable();
+  }
+
+  get() {
+    const id = asyncHooks.executionAsyncId();
+    if (this.map.has(id))
+      return this.map.get(id);
+  }
+
+  set(val) {
+    this.map.set(asyncHooks.executionAsyncId(), val);
+  }
+}
+
+module.exports = new AsyncContext();

lib/middlewares/express.js

@@ -1,16 +1,21 @@
 'use strict';
 
+const asyncContext = require('../async-context');
+
 module.exports = {
   buildMiddleware: function(provider) {
     return function(req, res, next) {
       provider.handler(req, res, next);
     };
   },
-  mainMiddleware: function(enable, authorize, handleRequest) {
+  mainMiddleware: function(enable, authorize, handleRequest, cls) {
     return function(req, res, next) {
       handleRequest(enable, authorize, req, res).then((handled) => {
         res.locals.miniprofiler = req.miniprofiler;
 
+        asyncContext.set(req.miniprofiler);
+        Object.defineProperty(req, 'miniprofiler', { get: () => asyncContext.get() });
+
         var render = res.render;
         res.render = function() {
           var renderArguments = arguments;
@@ -24,4 +29,4 @@ module.exports = {
       }).catch(next);
     };
   }
-};
+};

lib/middlewares/hapi.js

@@ -1,5 +1,7 @@
 'use strict';
 
+const asyncContext = require('../async-context');
+
 module.exports = {
   buildMiddleware: function(provider) {
     var plugin = {
@@ -25,7 +27,9 @@ module.exports = {
       register: (server, options, next) => {
         server.ext('onRequest', function(request, reply) {
           handleRequest(enable, authorize, request.raw.req, request.raw.res).then((handled) => {
-            request.app.miniprofiler = request.raw.req.miniprofiler;
+            asyncContext.set(request.raw.req.miniprofiler);
+            Object.defineProperty(request.app, 'miniprofiler', { get: () => asyncContext.get() });
+            Object.defineProperty(request.raw.req, 'miniprofiler', { get: () => asyncContext.get() });
 
             if (!handled)
               reply.continue();

lib/middlewares/koa.js

@@ -1,5 +1,7 @@
 'use strict';
 
+const asyncContext = require('../async-context');
+
 module.exports = {
   buildMiddleware: function(provider) {
     return function *(next) {
@@ -12,7 +14,10 @@ module.exports = {
   mainMiddleware: function(enable, authorize, handleRequest) {
     return function *(next) {
       var handled = yield handleRequest(enable, authorize, this.req, this.res);
-      this.state.miniprofiler = this.req.miniprofiler;
+
+      asyncContext.set(this.req.miniprofiler);
+      Object.defineProperty(this.state, 'miniprofiler', { get: () => asyncContext.get() });
+      Object.defineProperty(this.req, 'miniprofiler', { get: () => asyncContext.get() });
 
       if (this.render) {
         var render = this.render;

lib/miniprofiler.js

@@ -74,12 +74,12 @@ function handleRequest(enable, authorize, req, res) {
     }
 
     if (!requestPath.startsWith(resourcePath)) {
-      var id = startProfiling(req, enabled, authorized);
+      var extension = startProfiling(req, enabled, authorized);
       if (enabled) {
         res.on('finish', () => {
-          stopProfiling(req);
+          stopProfiling(extension, req);
         });
-        res.setHeader('X-MiniProfiler-Ids', `["${id}"]`);
+        res.setHeader('X-MiniProfiler-Ids', `["${extension.id}"]`);
       }
       return resolve(false);
     }
@@ -279,21 +279,19 @@ function include(id) {
   };
 
   request.miniprofiler = currentRequestExtension;
-  return currentRequestExtension.id;
+
+  return currentRequestExtension;
 }
 
 /*
  * Stops profiling the given request.
  */
- function stopProfiling(request){
-  var extension = request.miniprofiler;
+ function stopProfiling(extension, request){
   var time = process.hrtime();
 
   extension.stopTime = time;
   extension.stepGraph.stopTime = time;
 
-  delete request.miniprofiler;
-
   var json = describePerformance(extension, request);
   storage.set(extension.id, JSON.stringify(json));
 }

package.json

@@ -1,6 +1,6 @@
 {
   "name": "miniprofiler",
-  "version": "1.2.3",
+  "version": "2.0.0",
   "description": "A simple but effective mini-profiler.",
   "main": "lib/miniprofiler.js",
   "scripts": {
@@ -46,6 +46,7 @@
     "koa": "^1.2.1",
     "koa-route": "^2.4.2",
     "koa-views": "^4.1.0",
+    "debug": "^2.6.1",
     "mocha": "^2.5.3",
     "pug": "^2.0.0-beta2",
     "redis": "^2.6.1",

tests/concurrent-async-test.js

@@ -0,0 +1,37 @@
+'use strict';
+
+var expect = require('chai').expect;
+
+module.exports = function(server) {
+  describe('Concurrent Async Requests', function() {
+    before(server.setUp.bind(null, 'async'));
+    after(server.tearDown);
+
+    it('Each profile runs on its own context', function(done) {
+      let countDone = 0;
+      const partialDone = () => { if (++countDone === 2) done(); };
+
+      server.get('/', (err, response) => {
+        var ids = JSON.parse(response.headers['x-miniprofiler-ids']);
+        expect(ids).to.have.lengthOf(1);
+
+        server.post('/mini-profiler-resources/results/', { id: ids[0], popup: 1 }, (err, response, body) => {
+          var result = JSON.parse(body);
+          expect(result.Root.CustomTimings.async).to.have.lengthOf(2);
+          partialDone();
+        });
+      });
+
+      server.get('/?once=true', (err, response) => {
+        var ids = JSON.parse(response.headers['x-miniprofiler-ids']);
+        expect(ids).to.have.lengthOf(1);
+
+        server.post('/mini-profiler-resources/results/', { id: ids[0], popup: 1 }, (err, response, body) => {
+          var result = JSON.parse(body);
+          expect(result.Root.CustomTimings.async).to.have.lengthOf(1);
+          partialDone();
+        });
+      });
+    });
+  });
+};

tests/servers/async-provider.js

@@ -0,0 +1,21 @@
+'use strict';
+
+module.exports = function(obj) {
+  return {
+    name: 'dummy-async',
+    handler: function(req, res, next) {
+      obj.asyncFn = function() {
+        const timing = req.miniprofiler.startTimeQuery('async', 'dummy call');
+
+        return new Promise(resolve => {
+          setTimeout(() => {
+            req.miniprofiler.stopTimeQuery(timing);
+            resolve();
+          }, 25);
+        });
+      };
+
+      next();
+    }
+  };
+};

tests/servers/dummy-module.js

@@ -0,0 +1,5 @@
+'use strict';
+
+module.exports = {
+	asyncFn: () => Promise.resolve()
+};