Missing Time in Method Logs
Posted: May 19th, 2022, 12:06 pm
I am occasionally seeing inconsistencies when comparing service durations and the difference of the start times of the service and the next. Can the missing time somehow be accounted for in the end time, or can we have an alternate duration node added to the logs like "PrevStartToThisStart" (start time of self minus start time of the previous service) or "TimeToNextStart"/"DurationFromStartTimes" (start time of the next service minus start time of self)?
In the below example, the FormatDates service has a duration of 2101ms, but the next service didn't start until 6062ms after starting the FormatDates service.
In the below example, the FormatDates service has a duration of 2101ms, but the next service didn't start until 6062ms after starting the FormatDates service.
- In blue, there's 66ms between the end of the binary decision and the start of the FormatDates service. This is fairly negligible and expected.
- In purple, there's 3961ms between the end of the FormatDates service and start of the CompileEmpDetails service. This is a substantial amount of time missing and suggests that the FormatDates service took almost 3 times longer than is suggested. This is extremely difficult to find and I only noticed it because the durations of the services were not adding up to the duration of the method.