| ... | ... |
@@ -2290,11 +2290,13 @@ function cleanup_oscwrap {
|
| 2290 | 2290 |
function time_totals {
|
| 2291 | 2291 |
local elapsed_time |
| 2292 | 2292 |
local end_time |
| 2293 |
- local len=15 |
|
| 2293 |
+ local len=20 |
|
| 2294 | 2294 |
local xtrace |
| 2295 |
+ local unaccounted_time |
|
| 2295 | 2296 |
|
| 2296 | 2297 |
end_time=$(date +%s) |
| 2297 | 2298 |
elapsed_time=$(($end_time - $_TIME_BEGIN)) |
| 2299 |
+ unaccounted_time=$elapsed_time |
|
| 2298 | 2300 |
|
| 2299 | 2301 |
# pad 1st column this far |
| 2300 | 2302 |
for t in ${!_TIME_TOTAL[*]}; do
|
| ... | ... |
@@ -2311,16 +2313,19 @@ function time_totals {
|
| 2311 | 2311 |
echo |
| 2312 | 2312 |
echo "=========================" |
| 2313 | 2313 |
echo "DevStack Component Timing" |
| 2314 |
+ echo " (times are in seconds) " |
|
| 2314 | 2315 |
echo "=========================" |
| 2315 |
- printf "%-${len}s %3d\n" "Total runtime" "$elapsed_time"
|
|
| 2316 |
- echo |
|
| 2317 | 2316 |
for t in ${!_TIME_TOTAL[*]}; do
|
| 2318 | 2317 |
local v=${_TIME_TOTAL[$t]}
|
| 2319 | 2318 |
# because we're recording in milliseconds |
| 2320 | 2319 |
v=$(($v / 1000)) |
| 2321 | 2320 |
printf "%-${len}s %3d\n" "$t" "$v"
|
| 2321 |
+ unaccounted_time=$(($unaccounted_time - $v)) |
|
| 2322 | 2322 |
done |
| 2323 |
+ echo "-------------------------" |
|
| 2324 |
+ printf "%-${len}s %3d\n" "Unaccounted time" "$unaccounted_time"
|
|
| 2323 | 2325 |
echo "=========================" |
| 2326 |
+ printf "%-${len}s %3d\n" "Total runtime" "$elapsed_time"
|
|
| 2324 | 2327 |
|
| 2325 | 2328 |
$xtrace |
| 2326 | 2329 |
} |