[Buildbot] #2901: Finished time of a build step does not match the elapsedTime of the same step.

[Buildbot trac]

#2901: Finished time of a build step does not match the elapsedTime of the same
step.
-------------------+-----------------------
Reporter:  juj     |      Owner:
    Type:  defect  |     Status:  new
Priority:  major   |  Milestone:  undecided
 Version:  0.8.8   |   Keywords:
-------------------+-----------------------
 Steps to repro:

 1. Visit http://clb.demon.fi:8112/builders/win-incoming/builds/364
 2. Observe the status line of build step 8, which says that the step
 "Tests-browser-firefox" took 3 hrs, 38 mins, 39 secs.
 3. Click on the stdio link in that step, i.e. this page:
 4. Observe that at the very end of that step, "elapsedTime=916.263000" or
 15 minutes and 16 seconds is printed.

 There is a discrepancy of 3 hours, 23 minutes and 23 seconds in the
 reported times.

 Observing the actual execution of the task, that step indeed took 3 hours,
 instead of 15 minutes.

 Expected: The two times reported should be the same, and/or there should
 exist additional information fields which describe what consumed the
 excess 3 hours, 23 minutes and 23 seconds that is unaccounted for.

 PS. Navigating the logs of the Windows builder at
 http://clb.demon.fi:8112/builders/win-incoming , it can be seen that the
 builder is routinely losing hours of time to unaccounted work. I am unsure
 how to debug and reclaim what that might be. Any tips would be welcome!

--
Ticket URL: <http://trac.buildbot.net/ticket/2901>
Buildbot <http://buildbot.net/>
Buildbot: build/test automation