wrap caolan async.auto with logging for sub-call start/end timings

gistfile1.js

function loggedAsyncAuto (obj, doneCb) {
  var globalStart;
  function time () {
    return new Date().getTime();
  }
  function elapsed (now) {
    var s = '' + (now - globalStart);
    return _.times(5 - s.length, function () { return ' '; }).join('') + s;
  }
  var keys = _.keys(obj);
  var done = _.after(keys.length, function () {
    console.log('finished async.auto of [' + keys.join(', ') + '] in ' + (time() - globalStart) + ' ms');
  });
  var wrappedObj = _.object(keys, _.map(obj, function (fnish, name) {
    var fn;
    var wrappedFn = function (cb, results) {
      var myStart = time();
      if (!globalStart) {
        globalStart = myStart;
        console.log(elapsed(myStart) + ' starting async.auto of [' + keys.join(', ') + ']');
      }
      console.log(elapsed(myStart) + '  start ' + name);
      fn(function () {
        var now = time();
        console.log(elapsed(now) + ' finish ' + name + ' in ' + (now - myStart) + ' ms');
        done();
        cb.apply(this, arguments);
      }, results);
    };
    if (_.isFunction(fnish)) {
      fn = fnish;
      return wrappedFn;
    } else {
      fn = fnish[fnish.length - 1];
      var rval = fnish.slice();
      rval[rval.length - 1] = wrappedFn;
      return rval;
    }
  }));
  async.auto(wrappedObj, doneCb);
}