An output like the following is not helpful:

    Timer summary in microseconds (40 records):
           Mark    Elapsed  Stage
              0          0  reset
    ...
     56,448,158      4,845  fit_image_load
     56,448,186         28  fit_image_load
     56,448,187          1  fit_image_load
     56,451,971      3,784  fit_image_load
     56,483,951     31,980  fit_image_load
     56,483,956          5  fit_image_load
     56,483,984         28  fit_image_load
     56,484,001         17  fit_image_load
     56,484,008          7  fit_image_load

It provides no clue about what is happening in the fit_image_load stage.

Add the bootstage ID to the report. Now we get:

    Timer summary in microseconds (40 records):
           Mark    Elapsed    ID Stage
              0          0   171 reset
      3,181,017  3,181,017   178 board_init_f
      3,229,282     48,265   179 board_init_r
      4,184,866    955,584    64 eth_common_init
      4,280,304     95,438    65 eth_initialize
      4,284,509      4,205   186 main_loop
      4,390,704    106,195   180 usb_start
      5,694,146  1,303,442   187 cli_loop
     59,908,029 54,213,883   184 bootm_start
     59,908,031          2     1 boot_get_kernel
     59,912,875      4,844   100 fit_image_load
     59,912,903         28   101 fit_image_load
     59,912,904          1   102 fit_image_load
     59,916,688      3,784   110 fit_image_load
     59,948,668     31,980   105 fit_image_load
     59,948,673          5   106 fit_image_load
    ...

Signed-off-by: Heinrich Schuchardt <heinrich.schucha...@canonical.com>
---
 common/bootstage.c | 4 ++--
 1 file changed, 2 insertions(+), 2 deletions(-)

diff --git a/common/bootstage.c b/common/bootstage.c
index 4532100acea..0810c4e5271 100644
--- a/common/bootstage.c
+++ b/common/bootstage.c
@@ -254,7 +254,7 @@ static uint32_t print_time_record(struct bootstage_record 
*rec, uint32_t prev)
                print_grouped_ull(rec->time_us, BOOTSTAGE_DIGITS);
                print_grouped_ull(rec->time_us - prev, BOOTSTAGE_DIGITS);
        }
-       printf("  %s\n", get_record_name(buf, sizeof(buf), rec));
+       printf("  %4u %s\n", rec->id, get_record_name(buf, sizeof(buf), rec));
 
        return rec->time_us;
 }
@@ -333,7 +333,7 @@ void bootstage_report(void)
 
        printf("Timer summary in microseconds (%d records):\n",
               data->rec_count);
-       printf("%11s%11s  %s\n", "Mark", "Elapsed", "Stage");
+       printf("%11s%11s  %4s %s\n", "Mark", "Elapsed", "ID", "Stage");
 
        prev = print_time_record(rec, 0);
 
-- 
2.48.1

Reply via email to