2009-07-06 16:21:22

by Oleksij Rempel

[permalink] [raw]
Subject: PM: resume devices took 9.176 seconds

Hallo all,
it testing a lot git tree especially suspend and resume. With my new
Intel DG45ID board i get always this trace:

============================================
[ 176.763922] PM: resume devices took 9.176 seconds
[ 176.763924] ------------[ cut here ]------------
[ 176.763930] WARNING: at kernel/power/suspend_test.c:52
suspend_test_finish+0x7c/0x80()
[ 176.763932] Hardware name:
[ 176.763933] Component: resume devices
[ 176.763934] Modules linked in: binfmt_misc kvm_intel kvm
snd_hda_codec_intelhdmi snd_hda_codec_idt snd_hda_intel snd_hda_codec
snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi
snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd
psmouse iTCO_wdt iTCO_vendor_support serio_raw e1000e soundcore
snd_page_alloc
[ 176.763952] Pid: 4180, comm: bash Not tainted
2.6.31-rc1-25446-g1ae8c0a-dirty #3
[ 176.763953] Call Trace:
[ 176.763958] [<ffffffff8104a8e8>] warn_slowpath_common+0x78/0xb0
[ 176.763961] [<ffffffff8104a97c>] warn_slowpath_fmt+0x3c/0x40
[ 176.763964] [<ffffffff8107ce7c>] suspend_test_finish+0x7c/0x80
[ 176.763966] [<ffffffff8107cac1>] suspend_devices_and_enter+0xb1/0x210
[ 176.763969] [<ffffffff8107cd4a>] enter_state+0x12a/0x150
[ 176.763971] [<ffffffff8107c2a1>] state_store+0x91/0x100
[ 176.763974] [<ffffffff8121b177>] kobj_attr_store+0x17/0x20
[ 176.763978] [<ffffffff8115a250>] sysfs_write_file+0xe0/0x160
[ 176.763981] [<ffffffff810f8468>] vfs_write+0xb8/0x1a0
[ 176.763984] [<ffffffff810f63f8>] ? filp_close+0x58/0x90
[ 176.763987] [<ffffffff810f8f1c>] sys_write+0x4c/0x80
[ 176.763991] [<ffffffff8100b66b>] system_call_fastpath+0x16/0x1b
[ 176.763993] ---[ end trace 50ea4941e30c27fd ]---
[ 176.766367] PM: Finishing wakeup.
[ 176.766369] Restarting tasks ... done.
============================================================

this is related with the commit:
commit 77437fd4e61f87cc94d9314baa5cbf50e3ccdf54
Author: David Brownell <[email protected]>
Date: Wed Jul 23 21:28:33 2008 -0700

probobly it's depend on this message:
[ 173.076075] ata1: link is slow to respond, please be patient (ready=0)

so it's looks normal for this board and harddrive. I think it should be
some way to change default TEST_SUSPEND_SECONDS by .config or
kernel-parameter. I changed default settings to 10 in
kernel/power/suspend_test.c but this is not the way it should be done.


Thenks
Alexey


2009-07-18 11:49:39

by Pavel Machek

[permalink] [raw]
Subject: Re: PM: resume devices took 9.176 seconds

On Mon 2009-07-06 18:21:15, Alexey Fisher wrote:
> Hallo all,
> it testing a lot git tree especially suspend and resume. With my new
> Intel DG45ID board i get always this trace:
>
> ============================================
> [ 176.763922] PM: resume devices took 9.176 seconds
> [ 176.763924] ------------[ cut here ]------------
> [ 176.763930] WARNING: at kernel/power/suspend_test.c:52
> suspend_test_finish+0x7c/0x80()
>
> this is related with the commit:
> commit 77437fd4e61f87cc94d9314baa5cbf50e3ccdf54
> Author: David Brownell <[email protected]>
> Date: Wed Jul 23 21:28:33 2008 -0700
>
> probobly it's depend on this message:
> [ 173.076075] ata1: link is slow to respond, please be patient (ready=0)
>
> so it's looks normal for this board and harddrive. I think it should be
> some way to change default TEST_SUSPEND_SECONDS by .config or
> kernel-parameter. I changed default settings to 10 in
> kernel/power/suspend_test.c but this is not the way it should be done.

I'm not sure if that's normal. Does it also delay on boot?

--
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html

2009-07-18 13:46:10

by Oleksij Rempel

[permalink] [raw]
Subject: Re: PM: resume devices took 9.176 seconds

Pavel Machek schrieb:
> On Mon 2009-07-06 18:21:15, Alexey Fisher wrote:
>> Hallo all,
>> it testing a lot git tree especially suspend and resume. With my new
>> Intel DG45ID board i get always this trace:
>>
>> ============================================
>> [ 176.763922] PM: resume devices took 9.176 seconds
>> [ 176.763924] ------------[ cut here ]------------
>> [ 176.763930] WARNING: at kernel/power/suspend_test.c:52
>> suspend_test_finish+0x7c/0x80()
>>
>> this is related with the commit:
>> commit 77437fd4e61f87cc94d9314baa5cbf50e3ccdf54
>> Author: David Brownell <[email protected]>
>> Date: Wed Jul 23 21:28:33 2008 -0700
>>
>> probobly it's depend on this message:
>> [ 173.076075] ata1: link is slow to respond, please be patient (ready=0)
>>
>> so it's looks normal for this board and harddrive. I think it should be
>> some way to change default TEST_SUSPEND_SECONDS by .config or
>> kernel-parameter. I changed default settings to 10 in
>> kernel/power/suspend_test.c but this is not the way it should be done.
>
> I'm not sure if that's normal. Does it also delay on boot?

I have same time with test_suspend=mem on boot and with normal suspend.
Probably there is some thing wrong but it's seems for like it do the
same in windows vista like in linux. In linux i use clean suspend... no
distro scripts used... in dmesg i ken see that sata harddrive need some
time to wake, so i think it's the reason for it.