added logs for global timeout

Author: DavertMik

lib/listener/globalTimeout.js

@@ -3,6 +3,7 @@ const output = require('../output')
 const recorder = require('../recorder')
 const Config = require('../config')
 const { timeouts } = require('../store')
+const debug = require('debug')('codeceptjs:timeout')
 const TIMEOUT_ORDER = require('../step').TIMEOUT_ORDER
 
 module.exports = function () {
@@ -20,6 +21,8 @@ module.exports = function () {
     suiteTimeout = []
     let timeoutConfig = Config.get('timeout')
 
+    debug('config:', timeoutConfig || 'none')
+
     if (timeoutConfig) {
       if (!Number.isNaN(+timeoutConfig)) {
         checkForSeconds(timeoutConfig)
@@ -38,8 +41,10 @@ module.exports = function () {
       }
     }
 
+    debug('current suite timeout:', suite.totalTimeout || 'none')
     if (suite.totalTimeout) suiteTimeout.push(suite.totalTimeout)
     output.log(`Timeouts: ${suiteTimeout}`)
+    debug('active timeouts', suiteTimeout)
   })
 
   event.dispatcher.on(event.test.before, test => {
@@ -81,8 +86,10 @@ module.exports = function () {
     if (typeof timeout !== 'number') return
 
     if (timeout < 0) {
+      debug('Previous steps timed out, setting timeout to 0.01s')
       step.setTimeout(0.01, TIMEOUT_ORDER.testOrSuite)
     } else {
+      debug(`Setting timeout ${timeout}ms for step ${step.toCode().trim()}`)
       step.setTimeout(timeout, TIMEOUT_ORDER.testOrSuite)
     }
   })
@@ -91,7 +98,9 @@ module.exports = function () {
     if (typeof timeout === 'number' && !Number.isNaN(timeout)) timeout -= step.duration
 
     if (typeof timeout === 'number' && timeout <= 0 && recorder.isRunning()) {
+      debug(`step ${step.toCode().trim()} timed out`)
       if (currentTest && currentTest.callback) {
+        debug(`Failing test ${currentTest.title} with timeout ${currentTimeout}s`)
         recorder.reset()
         // replace mocha timeout with custom timeout
         currentTest.timeout(0)