summaryrefslogtreecommitdiff
path: root/src/console
diff options
context:
space:
mode:
authorKyösti Mälkki <kyosti.malkki@gmail.com>2019-11-02 14:12:18 +0200
committerPatrick Georgi <pgeorgi@google.com>2019-11-11 10:31:29 +0000
commit45ddb4344f73051855da6d4e87a5ba4b4c66af71 (patch)
tree1a62d88791bb5ff4ead56d395925829b69a84e54 /src/console
parent19825e8e375d1c4d0448e7ffe3bb2e2c230c9eea (diff)
console,boot_state: Exclude printk() from reported times
Use monotonic timer to accumulate the time spent in console code. For bootblock and romstage, only stage total is reported. For ramstage each boot_state is reported individually. Change-Id: Id3998bab553ff803a93257a3f2c7bfea44c31729 Signed-off-by: Kyösti Mälkki <kyosti.malkki@gmail.com> Reviewed-on: https://review.coreboot.org/c/coreboot/+/36574 Tested-by: build bot (Jenkins) <no-reply@coreboot.org> Reviewed-by: Paul Menzel <paulepanter@users.sourceforge.net> Reviewed-by: Nico Huber <nico.h@gmx.de>
Diffstat (limited to 'src/console')
-rw-r--r--src/console/printk.c46
1 files changed, 46 insertions, 0 deletions
diff --git a/src/console/printk.c b/src/console/printk.c
index 15c599dce0..4f9f547bc5 100644
--- a/src/console/printk.c
+++ b/src/console/printk.c
@@ -21,14 +21,56 @@
#include <smp/node.h>
#include <stddef.h>
#include <trace.h>
+#include <timer.h>
#if (!defined(__PRE_RAM__) && CONFIG(HAVE_ROMSTAGE_CONSOLE_SPINLOCK)) || !CONFIG(HAVE_ROMSTAGE_CONSOLE_SPINLOCK)
DECLARE_SPIN_LOCK(console_lock)
#endif
+#define TRACK_CONSOLE_TIME (CONFIG(HAVE_MONOTONIC_TIMER) && \
+ (ENV_RAMSTAGE || !CONFIG(CAR_GLOBAL_MIGRATION)))
+
+static struct mono_time mt_start, mt_stop;
+static long console_usecs;
+
+static void console_time_run(void)
+{
+ if (TRACK_CONSOLE_TIME)
+ timer_monotonic_get(&mt_start);
+}
+
+static void console_time_stop(void)
+{
+ if (TRACK_CONSOLE_TIME) {
+ timer_monotonic_get(&mt_stop);
+ console_usecs += mono_time_diff_microseconds(&mt_start, &mt_stop);
+ }
+}
+
+void console_time_report(void)
+{
+ if (!TRACK_CONSOLE_TIME)
+ return;
+
+ printk(BIOS_DEBUG, "Accumulated console time in " ENV_STRING " %ld ms\n",
+ DIV_ROUND_CLOSEST(console_usecs, USECS_PER_MSEC));
+}
+
+long console_time_get_and_reset(void)
+{
+ if (!TRACK_CONSOLE_TIME)
+ return 0;
+
+ long elapsed = console_usecs;
+ console_usecs = 0;
+ return elapsed;
+}
+
void do_putchar(unsigned char byte)
{
+ console_time_run();
console_tx_byte(byte);
+ console_time_stop();
}
static void wrap_putchar(unsigned char byte, void *data)
@@ -61,6 +103,8 @@ int do_vprintk(int msg_level, const char *fmt, va_list args)
spin_lock(&console_lock);
#endif
+ console_time_run();
+
if (log_this == CONSOLE_LOG_FAST) {
i = vtxprintf(wrap_putchar_cbmemc, fmt, args, NULL);
} else {
@@ -68,6 +112,8 @@ int do_vprintk(int msg_level, const char *fmt, va_list args)
console_tx_flush();
}
+ console_time_stop();
+
#ifdef __PRE_RAM__
#if CONFIG(HAVE_ROMSTAGE_CONSOLE_SPINLOCK)
spin_unlock(romstage_console_lock());