Changeset 61458 in vbox

Timestamp:

Jun 4, 2016 12:02:44 AM (8 years ago)

Author:

vboxsync

Message:

testdriver/base.py: log more in the timeout adjusting / deadline handling to figure out what goes wrong on windows.

File:

• trunk/src/VBox/ValidationKit/testdriver/base.py (modified) (3 diffs)

trunk/src/VBox/ValidationKit/testdriver/base.py

@@ -845 +845 @@
         if self.secTimeoutAbs is not None:
             self.secTimeoutAbs = long(self.secTimeoutAbs);
+            reporter.log('secTimeoutAbs: %s' % (self.secTimeoutAbs,));
+        else:
+            reporter.log('TESTBOX_TIMEOUT_ABS not found in the environment');
+
 
         # Distance from secTimeoutAbs that timeouts should be adjusted to.
@@ -869 +873 @@
         print >> sys.stderr, "testdriver.base: asNormalActions   = '%s'" % self.asNormalActions;
         print >> sys.stderr, "testdriver.base: asActions         = '%s'" % self.asActions;
+        print >> sys.stderr, "testdriver.base: secTimeoutAbs     = '%s'" % self.secTimeoutAbs;
+        for sVar in sorted(os.environ.keys()):
+            print >> sys.stderr, "os.environ[%s] = '%s'" % (sVar, os.environ[sVar],);
 
     #
@@ -1244 +1251 @@
                     reporter.log('adjusting timeout: %s ms -> %s ms (deadline)\n' % (cMsTimeout, cMsToDeadline,));
                     return cMsToDeadline;
-
-            #else: Don't bother, we've passed the deadline.
+                reporter.log('adjustTimeoutMs: cMsTimeout (%s) > cMsToDeadline (%s)' % (cMsTimeout, cMsToDeadline,));
+            else:
+                # Don't bother, we've passed the deadline.
+                reporter.log('adjustTimeoutMs: ooops! cMsToDeadline=%s (%s), utils.timestampMilli()=%s'
+                             % (cMsToDeadline, cMsToDeadline*1000, utils.timestampMilli(),));
 
         # Only enforce the minimum timeout if specified.