From mboxrd@z Thu Jan  1 00:00:00 1970
From: Johannes Berg <johannes-cdvu00un1VgdHxzADdlk8Q@public.gmane.org>
Subject: Re: [Bug #13306] hibernate slow on _second_ run
Date: Thu, 04 Jun 2009 16:26:10 +0200
Message-ID: <1244125570.22576.78.camel@johannes.local>
References: <4to6E2myxvG.A.muC.aIbIKB@chimera>
	 <200906032305.07551.rjw@sisk.pl> <1244064315.22576.5.camel@johannes.local>
	 <200906032351.01900.rjw@sisk.pl>
Mime-Version: 1.0
Content-Type: multipart/signed; micalg="pgp-sha1"; protocol="application/pgp-signature"; boundary="=-OowC4ytkJPBWlB463KAI"
Return-path: <kernel-testers-owner-u79uwXL29TY76Z2rM5mHXA@public.gmane.org>
In-Reply-To: <200906032351.01900.rjw-KKrjLPT3xs0@public.gmane.org>
Sender: kernel-testers-owner-u79uwXL29TY76Z2rM5mHXA@public.gmane.org
List-ID: <kernel-testers.vger.kernel.org>
To: "Rafael J. Wysocki" <rjw-KKrjLPT3xs0@public.gmane.org>
Cc: Linux Kernel Mailing List <linux-kernel-u79uwXL29TY76Z2rM5mHXA@public.gmane.org>, Kernel Testers List <kernel-testers-u79uwXL29TY76Z2rM5mHXA@public.gmane.org>, Steven Rostedt <srostedt-H+wXaHxf7aLQT0dZR+AlfA@public.gmane.org>


--=-OowC4ytkJPBWlB463KAI
Content-Type: text/plain
Content-Transfer-Encoding: quoted-printable

Alright, I have managed to trace this.

http://johannes.sipsolutions.net/files/hibernate-delay-dmesg.txt

has the dmesg, you can clearly see the delay:
[50265.143330] ACPI: Waking up from system sleep state S4
[50265.679141] HDA Intel 0000:00:08.0: restoring config space at offset 0x1=
 (was 0xb00006, writing 0xb00002)
[50265.689525] forcedeth 0000:00:0a.0: restoring config space at offset 0x1=
 (was 0xb00003, writing 0xb00007)
[50265.699775] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was=
 0xb00403, writing 0xb00407)
[50286.294910] pci 0000:00:03.2: PME# disabled

I managed to get an entire function trace between

[50265.143330] ACPI: Waking up from system sleep state S4

and

[50290.439040] Restarting tasks ... done.

which I've uploaded to

http://johannes.sipsolutions.net/files/hibernate-delay-trace.bz2

(WARNING: uncompresses to 574MiB!)

I'm not sure why the timing in there doesn't show the delay, Steven was
taking a look at that.

johannes

--=-OowC4ytkJPBWlB463KAI
Content-Type: application/pgp-signature; name="signature.asc"
Content-Description: This is a digitally signed message part

-----BEGIN PGP SIGNATURE-----

iQIcBAABAgAGBQJKJ9l/AAoJEODzc/N7+QmasBsP/ioLk91yR6uHRv9vCjymR0xp
ICoifV8KWFUqkEc8bnh9WXfKyMU29EzPNkarWalfTec8DryRqzGa1cEZmXEAbFen
b3qc61md5IGrh1ZNOKJKNcyGBTZ128sV4vj0ss8Q5lymx+0iSJlqmAfAJ5tf0vi7
XsP2+P8xBbdlwJatsFmX6wa+RwriiB9/JqhJyCxP2w8V/qSeuWePnITxTL4rD/NQ
MzDDSK6J6Zzz0wjaa9qNd8PNl6Q18dOlc4KrZWbknyhJ3l57TLHTmlpCVys8TGRR
WvjV8jtFU7rVqMvtalrCZ+K227WAt+dVofGb9G2NYzctYz8o5MkTqK22J5M8ozve
IPFp2X0qHbglUK9w/DHuzI9UxFTj9G87jBNj+xroOLrlI9lYp7hgJrIK19/To7Ib
rz7EcG6qUBUh8IFJRBdn83/mfz4BR1zqlB2StlvU1u6eM1IFMcCLrwpjn7VWvdoj
PeC5BaJFGa4rX7mu9+aQi8QbpkRcVIQ2yeF75vbGlz5lezKqvn/KQnWBNCVQaJ5Q
fmIJWaB0hX6udXcPNfOOwZ6kj9Xs97DemQw4Y0o+jQe0ae/0cy/vosUtN1ipGe8u
XP0GRK+nD7/z1ldiK8MdqEXbrfV87TXoKplfIcQtxR9ryZTcp/jLigxcYGARwVm9
8TeQhYM18sc7IN3Zmqcj
=OCDZ
-----END PGP SIGNATURE-----

--=-OowC4ytkJPBWlB463KAI--