public inbox for linux-pm@vger.kernel.org
 help / color / mirror / Atom feed
* [PATCH] suspend: add initcall_debug style timing for suspend/resume
@ 2009-12-06 20:35 Arjan van de Ven
  0 siblings, 0 replies; 5+ messages in thread
From: Arjan van de Ven @ 2009-12-06 20:35 UTC (permalink / raw)
  To: Linus Torvalds; +Cc: ACPI Devel Maling List, pm list, LKML


>From fcd52b97aa02f19baa570e4a62dfdfab0faed976 Mon Sep 17 00:00:00 2001
From: Arjan van de Ven <arjan@linux.intel.com>
Date: Sun, 6 Dec 2009 12:32:28 -0800
Subject: [PATCH] suspend: add initcall_debug style timing for suspend/resume

In order to diagnose overall suspend/resume times, we need
basic instrumentation to break down the total time into per
device timing, similar to initcall_debug.

This patch adds the basic timing instrumentation, needed
for a scritps/bootgraph.pl equivalent or humans.
The bootgraph.pl program is still a work in progress, but
is far enough along to know that this patch is sufficient.

Signed-off-by: Arjan van de Ven <arjan@linux.intel.com>
---
 drivers/base/power/main.c |   29 +++++++++++++++++++++++++++++
 include/linux/init.h      |    2 ++
 2 files changed, 31 insertions(+), 0 deletions(-)

diff --git a/drivers/base/power/main.c b/drivers/base/power/main.c
index 8aa2443..2ad345c 100644
--- a/drivers/base/power/main.c
+++ b/drivers/base/power/main.c
@@ -25,6 +25,7 @@
 #include <linux/resume-trace.h>
 #include <linux/rwsem.h>
 #include <linux/interrupt.h>
+#include <linux/sched.h>
 
 #include "../base.h"
 #include "power.h"
@@ -172,6 +173,12 @@ static int pm_op(struct device *dev,
 		 pm_message_t state)
 {
 	int error = 0;
+	ktime_t calltime, delta, rettime;
+
+	if (initcall_debug) {
+		printk("calling  %s+ @ %i\n", dev_name(dev), task_pid_nr(current));
+		calltime = ktime_get();
+	}
 
 	switch (state.event) {
 #ifdef CONFIG_SUSPEND
@@ -219,6 +226,14 @@ static int pm_op(struct device *dev,
 	default:
 		error = -EINVAL;
 	}
+
+	if (initcall_debug) {
+		rettime = ktime_get();
+		delta = ktime_sub(rettime, calltime);
+		printk("initcall %s+ returned %d after %Ld usecs\n", dev_name(dev),
+			error, (unsigned long long) ktime_to_ns(delta) >> 10);
+	}
+
 	return error;
 }
 
@@ -236,6 +251,12 @@ static int pm_noirq_op(struct device *dev,
 			pm_message_t state)
 {
 	int error = 0;
+	ktime_t calltime, delta, rettime;
+
+	if (initcall_debug) {
+		printk("calling  %s_i+ @ %i\n", dev_name(dev), task_pid_nr(current));
+		calltime = ktime_get();
+	}
 
 	switch (state.event) {
 #ifdef CONFIG_SUSPEND
@@ -283,6 +304,14 @@ static int pm_noirq_op(struct device *dev,
 	default:
 		error = -EINVAL;
 	}
+
+	if (initcall_debug) {
+		rettime = ktime_get();
+		delta = ktime_sub(rettime, calltime);
+		printk("initcall %s_i+ returned %d after %Ld usecs\n", dev_name(dev),
+			error, (unsigned long long) ktime_to_ns(delta) >> 10);
+	}
+
 	return error;
 }
 
diff --git a/include/linux/init.h b/include/linux/init.h
index ff8bde5..ab1d31f 100644
--- a/include/linux/init.h
+++ b/include/linux/init.h
@@ -149,6 +149,8 @@ void prepare_namespace(void);
 
 extern void (*late_time_init)(void);
 
+extern int initcall_debug;
+
 #endif
   
 #ifndef MODULE
-- 
1.6.2.5


-- 
Arjan van de Ven 	Intel Open Source Technology Centre
For development, discussion and tips for power savings, 
visit http://www.lesswatts.org

^ permalink raw reply related	[flat|nested] 5+ messages in thread

* Re: [PATCH] suspend: add initcall_debug style timing for suspend/resume
       [not found] <20091206123552.7be316b2@infradead.org>
@ 2009-12-12 18:27 ` Rafael J. Wysocki
  2009-12-12 18:29   ` Rafael J. Wysocki
  2009-12-12 21:47   ` Arjan van de Ven
  0 siblings, 2 replies; 5+ messages in thread
From: Rafael J. Wysocki @ 2009-12-12 18:27 UTC (permalink / raw)
  To: Arjan van de Ven; +Cc: ACPI Devel Maling List, pm list, Linus Torvalds, LKML

On Sunday 06 December 2009, Arjan van de Ven wrote:
> 
> From fcd52b97aa02f19baa570e4a62dfdfab0faed976 Mon Sep 17 00:00:00 2001
> From: Arjan van de Ven <arjan@linux.intel.com>
> Date: Sun, 6 Dec 2009 12:32:28 -0800
> Subject: [PATCH] suspend: add initcall_debug style timing for suspend/resume
> 
> In order to diagnose overall suspend/resume times, we need
> basic instrumentation to break down the total time into per
> device timing, similar to initcall_debug.
> 
> This patch adds the basic timing instrumentation, needed
> for a scritps/bootgraph.pl equivalent or humans.
> The bootgraph.pl program is still a work in progress, but
> is far enough along to know that this patch is sufficient.
> 
> Signed-off-by: Arjan van de Ven <arjan@linux.intel.com>
> ---
>  drivers/base/power/main.c |   29 +++++++++++++++++++++++++++++
>  include/linux/init.h      |    2 ++
>  2 files changed, 31 insertions(+), 0 deletions(-)
> 
> diff --git a/drivers/base/power/main.c b/drivers/base/power/main.c
> index 8aa2443..2ad345c 100644
> --- a/drivers/base/power/main.c
> +++ b/drivers/base/power/main.c
> @@ -25,6 +25,7 @@
>  #include <linux/resume-trace.h>
>  #include <linux/rwsem.h>
>  #include <linux/interrupt.h>
> +#include <linux/sched.h>
>  
>  #include "../base.h"
>  #include "power.h"
> @@ -172,6 +173,12 @@ static int pm_op(struct device *dev,
>  		 pm_message_t state)
>  {
>  	int error = 0;
> +	ktime_t calltime, delta, rettime;
> +
> +	if (initcall_debug) {
> +		printk("calling  %s+ @ %i\n", dev_name(dev), task_pid_nr(current));
> +		calltime = ktime_get();
> +	}
>  
>  	switch (state.event) {
>  #ifdef CONFIG_SUSPEND
> @@ -219,6 +226,14 @@ static int pm_op(struct device *dev,
>  	default:
>  		error = -EINVAL;
>  	}
> +
> +	if (initcall_debug) {
> +		rettime = ktime_get();
> +		delta = ktime_sub(rettime, calltime);
> +		printk("initcall %s+ returned %d after %Ld usecs\n", dev_name(dev),
> +			error, (unsigned long long) ktime_to_ns(delta) >> 10);

I've changed that "initcall" to "call".  Hopefully that won't break your scripts. ;-)

Rafael

^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: [PATCH] suspend: add initcall_debug style timing for suspend/resume
  2009-12-12 18:27 ` [PATCH] suspend: add initcall_debug style timing for suspend/resume Rafael J. Wysocki
@ 2009-12-12 18:29   ` Rafael J. Wysocki
  2009-12-12 18:31     ` Rafael J. Wysocki
  2009-12-12 21:47   ` Arjan van de Ven
  1 sibling, 1 reply; 5+ messages in thread
From: Rafael J. Wysocki @ 2009-12-12 18:29 UTC (permalink / raw)
  To: Arjan van de Ven; +Cc: ACPI Devel Maling List, pm list, Linus Torvalds, LKML

On Saturday 12 December 2009, Rafael J. Wysocki wrote:
> On Sunday 06 December 2009, Arjan van de Ven wrote:
> > 
> > From fcd52b97aa02f19baa570e4a62dfdfab0faed976 Mon Sep 17 00:00:00 2001
> > From: Arjan van de Ven <arjan@linux.intel.com>
> > Date: Sun, 6 Dec 2009 12:32:28 -0800
> > Subject: [PATCH] suspend: add initcall_debug style timing for suspend/resume
> > 
> > In order to diagnose overall suspend/resume times, we need
> > basic instrumentation to break down the total time into per
> > device timing, similar to initcall_debug.
> > 
> > This patch adds the basic timing instrumentation, needed
> > for a scritps/bootgraph.pl equivalent or humans.
> > The bootgraph.pl program is still a work in progress, but
> > is far enough along to know that this patch is sufficient.
> > 
> > Signed-off-by: Arjan van de Ven <arjan@linux.intel.com>
> > ---
> >  drivers/base/power/main.c |   29 +++++++++++++++++++++++++++++
> >  include/linux/init.h      |    2 ++
> >  2 files changed, 31 insertions(+), 0 deletions(-)
> > 
> > diff --git a/drivers/base/power/main.c b/drivers/base/power/main.c
> > index 8aa2443..2ad345c 100644
> > --- a/drivers/base/power/main.c
> > +++ b/drivers/base/power/main.c
> > @@ -25,6 +25,7 @@
> >  #include <linux/resume-trace.h>
> >  #include <linux/rwsem.h>
> >  #include <linux/interrupt.h>
> > +#include <linux/sched.h>
> >  
> >  #include "../base.h"
> >  #include "power.h"
> > @@ -172,6 +173,12 @@ static int pm_op(struct device *dev,
> >  		 pm_message_t state)
> >  {
> >  	int error = 0;
> > +	ktime_t calltime, delta, rettime;
> > +
> > +	if (initcall_debug) {
> > +		printk("calling  %s+ @ %i\n", dev_name(dev), task_pid_nr(current));
> > +		calltime = ktime_get();
> > +	}
> >  
> >  	switch (state.event) {
> >  #ifdef CONFIG_SUSPEND
> > @@ -219,6 +226,14 @@ static int pm_op(struct device *dev,
> >  	default:
> >  		error = -EINVAL;
> >  	}
> > +
> > +	if (initcall_debug) {
> > +		rettime = ktime_get();
> > +		delta = ktime_sub(rettime, calltime);
> > +		printk("initcall %s+ returned %d after %Ld usecs\n", dev_name(dev),
> > +			error, (unsigned long long) ktime_to_ns(delta) >> 10);
> 
> I've changed that "initcall" to "call".  Hopefully that won't break your scripts. ;-)

In addition to that I've changed printk to pr_infor everywhere in the patch,
is that fine with you?

Rafael

^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: [PATCH] suspend: add initcall_debug style timing for suspend/resume
  2009-12-12 18:29   ` Rafael J. Wysocki
@ 2009-12-12 18:31     ` Rafael J. Wysocki
  0 siblings, 0 replies; 5+ messages in thread
From: Rafael J. Wysocki @ 2009-12-12 18:31 UTC (permalink / raw)
  To: Arjan van de Ven; +Cc: ACPI Devel Maling List, pm list, Linus Torvalds, LKML

On Saturday 12 December 2009, Rafael J. Wysocki wrote:
> On Saturday 12 December 2009, Rafael J. Wysocki wrote:
> > On Sunday 06 December 2009, Arjan van de Ven wrote:
> > > 
> > > From fcd52b97aa02f19baa570e4a62dfdfab0faed976 Mon Sep 17 00:00:00 2001
> > > From: Arjan van de Ven <arjan@linux.intel.com>
> > > Date: Sun, 6 Dec 2009 12:32:28 -0800
> > > Subject: [PATCH] suspend: add initcall_debug style timing for suspend/resume
> > > 
> > > In order to diagnose overall suspend/resume times, we need
> > > basic instrumentation to break down the total time into per
> > > device timing, similar to initcall_debug.
> > > 
> > > This patch adds the basic timing instrumentation, needed
> > > for a scritps/bootgraph.pl equivalent or humans.
> > > The bootgraph.pl program is still a work in progress, but
> > > is far enough along to know that this patch is sufficient.
> > > 
> > > Signed-off-by: Arjan van de Ven <arjan@linux.intel.com>
> > > ---
> > >  drivers/base/power/main.c |   29 +++++++++++++++++++++++++++++
> > >  include/linux/init.h      |    2 ++
> > >  2 files changed, 31 insertions(+), 0 deletions(-)
> > > 
> > > diff --git a/drivers/base/power/main.c b/drivers/base/power/main.c
> > > index 8aa2443..2ad345c 100644
> > > --- a/drivers/base/power/main.c
> > > +++ b/drivers/base/power/main.c
> > > @@ -25,6 +25,7 @@
> > >  #include <linux/resume-trace.h>
> > >  #include <linux/rwsem.h>
> > >  #include <linux/interrupt.h>
> > > +#include <linux/sched.h>
> > >  
> > >  #include "../base.h"
> > >  #include "power.h"
> > > @@ -172,6 +173,12 @@ static int pm_op(struct device *dev,
> > >  		 pm_message_t state)
> > >  {
> > >  	int error = 0;
> > > +	ktime_t calltime, delta, rettime;
> > > +
> > > +	if (initcall_debug) {
> > > +		printk("calling  %s+ @ %i\n", dev_name(dev), task_pid_nr(current));
> > > +		calltime = ktime_get();
> > > +	}
> > >  
> > >  	switch (state.event) {
> > >  #ifdef CONFIG_SUSPEND
> > > @@ -219,6 +226,14 @@ static int pm_op(struct device *dev,
> > >  	default:
> > >  		error = -EINVAL;
> > >  	}
> > > +
> > > +	if (initcall_debug) {
> > > +		rettime = ktime_get();
> > > +		delta = ktime_sub(rettime, calltime);
> > > +		printk("initcall %s+ returned %d after %Ld usecs\n", dev_name(dev),
> > > +			error, (unsigned long long) ktime_to_ns(delta) >> 10);
> > 
> > I've changed that "initcall" to "call".  Hopefully that won't break your scripts. ;-)
> 
> In addition to that I've changed printk to pr_infor

Argh.  pr_info, that is.

Rafael

^ permalink raw reply	[flat|nested] 5+ messages in thread

* Re: [PATCH] suspend: add initcall_debug style timing for suspend/resume
  2009-12-12 18:27 ` [PATCH] suspend: add initcall_debug style timing for suspend/resume Rafael J. Wysocki
  2009-12-12 18:29   ` Rafael J. Wysocki
@ 2009-12-12 21:47   ` Arjan van de Ven
  1 sibling, 0 replies; 5+ messages in thread
From: Arjan van de Ven @ 2009-12-12 21:47 UTC (permalink / raw)
  To: Rafael J. Wysocki; +Cc: ACPI Devel Maling List, pm list, Linus Torvalds, LKML

On Sat, 12 Dec 2009 19:27:55 +0100
"Rafael J. Wysocki" <rjw@sisk.pl> wrote:
> I've changed that "initcall" to "call".  Hopefully that won't break
> your scripts. ;-)

it will, but I can adjust them no big deal



-- 
Arjan van de Ven 	Intel Open Source Technology Centre
For development, discussion and tips for power savings, 
visit http://www.lesswatts.org

^ permalink raw reply	[flat|nested] 5+ messages in thread

end of thread, other threads:[~2009-12-12 21:47 UTC | newest]

Thread overview: 5+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
     [not found] <20091206123552.7be316b2@infradead.org>
2009-12-12 18:27 ` [PATCH] suspend: add initcall_debug style timing for suspend/resume Rafael J. Wysocki
2009-12-12 18:29   ` Rafael J. Wysocki
2009-12-12 18:31     ` Rafael J. Wysocki
2009-12-12 21:47   ` Arjan van de Ven
2009-12-06 20:35 Arjan van de Ven

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox