Re: Debian 64 on Thinkpad T61p freezes on resume from hibernation
2010/11/19 Alexey A Nikitin <moonwalker@syrius.us>:
> 2010/11/19 Klistvud <quotations@aliceadsl.fr>:
>>
>> Anyway, given that your initial issue is resolved -- and obviously your
>> partitions do get mounted -- the first thing to do would be to look at the
>> logs. There you may find some pointers. Although, if you have like 4GB of
>> RAM, and you load lots of applications, then your resume from hibernation
>> will be quite long regardless.
>
> I'll look into logs tonight or tomorrow night and will post if I find anything.
>
Hi,
I've synchronized my watch with computer clock and ran hibernate/thaw
cycle, timing all observable events. Here is how it goes, with
messages from syslog:
~16:30:35 - I press power button
~16:30:54 - GRUB is loading... (it took me some time to enter BIOS password)
~16:30:58 - "Loading, please wait..." message after GRUB menu entry
selection, disk activity indicator lights up, screen goes black
~16:31:09 - disk activity indicator turns off and machine gives short
beep, screen stays black
~16:32:15 - first long beep, screen stays black, disk activity
indicator stays off
~16:33:50 - second long beep, disk activity comes alive.
Nov 19 16:33:51 syrius kernel: PM: Syncing filesystems ... done.
Nov 19 16:33:51 syrius kernel: Freezing user space processes ...
(elapsed 0.01 seconds) done.
Nov 19 16:33:51 syrius kernel: Freezing remaining freezable tasks ...
(elapsed 0.01 seconds) done.
Nov 19 16:33:51 syrius kernel: PM: Preallocating image memory... done
(allocated 895792 pages)
Nov 19 16:33:51 syrius kernel: PM: Allocated 3583168 kbytes in 2.31
seconds (1551.15 MB/s)
Nov 19 16:33:51 syrius kernel: Suspending console(s) (use
no_console_suspend to debug)
Nov 19 16:33:51 syrius kernel: ACPI handle has no context!
Nov 19 16:33:51 syrius kernel: sdhci-pci 0000:15:00.2: PCI INT C disabled
Nov 19 16:33:51 syrius kernel: ACPI handle has no context!
Nov 19 16:33:51 syrius kernel: ata2: port disabled. ignoring.
Nov 19 16:33:51 syrius kernel: ata_piix 0000:00:1f.1: PCI INT C disabled
Nov 19 16:33:51 syrius kernel: e1000e 0000:00:19.0: PME# enabled
Nov 19 16:33:51 syrius kernel: e1000e 0000:00:19.0: wake-up capability
enabled by ACPI
Nov 19 16:33:51 syrius kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
Nov 19 16:33:51 syrius kernel: sd 2:0:0:0: [sda] Synchronizing SCSI cache
Nov 19 16:33:51 syrius kernel: PM: freeze of devices complete after
226.898 msecs
Nov 19 16:33:51 syrius kernel: PM: late freeze of devices complete
after 0.941 msecs
Nov 19 16:33:51 syrius kernel: ACPI: Preparing to enter system sleep state S4
Nov 19 16:33:51 syrius kernel: PM: Saving platform NVS memory
Nov 19 16:33:51 syrius kernel: Disabling non-boot CPUs ...
Nov 19 16:33:52 syrius acpid: client connected from 1880[0:0]
Nov 19 16:33:52 syrius acpid: 1 client rule loaded
Nov 19 16:33:52 syrius kernel: CPU 1 is now offline
Nov 19 16:33:52 syrius kernel: SMP alternatives: switching to UP code
Nov 19 16:33:52 syrius kernel: Extended CMOS year: 2000
Nov 19 16:33:52 syrius kernel: PM: Creating hibernation image:
Nov 19 16:33:52 syrius kernel: PM: Need to copy 129512 pages
Nov 19 16:33:52 syrius kernel: PM: Restoring platform NVS memory
Nov 19 16:33:52 syrius kernel: Extended CMOS year: 2000
Nov 19 16:33:52 syrius kernel: Enabling non-boot CPUs ...
Nov 19 16:33:52 syrius kernel: SMP alternatives: switching to SMP code
Nov 19 16:33:52 syrius kernel: Booting Node 0 Processor 1 APIC 0x1
Nov 19 16:33:52 syrius kernel: CPU1 is up
Nov 19 16:33:52 syrius kernel: ACPI: Waking up from system sleep state S4
# here were messages about config restoration for ehci, ahci,
whateverci, nvidia card and network controllers
Nov 19 16:33:52 syrius kernel: PM: early restore of devices complete
after 54.337 msecs
#restoration of sound, ata and usb controllers
Nov 19 16:33:52 syrius kernel: thinkpad_acpi: ACPI backlight control
delay disabled
# now here is the interesting moment - logs show absolutely nothing
happening all that time until only ~ 2000-3000 msecs before this
entry.
# this ~163 seconds roughly correspond to time when disk activity
stopped and system made short beep
Nov 19 16:33:52 syrius kernel: PM: restore of devices complete after
162897.016 msecs
# rest of the USB devices reinitialization and NetworkManager startup
There is not much info in system to go with, but it seems to me that
there is something that takes an awful while for kernel to
restore/initialize. Any thoughts?
Best,
Alexey
--
This message was created with 100% recycled electrons
Reply to: