Bug 11491 - Wakeup after suspend to ram realy slow (1-2 minutes)
Summary: Wakeup after suspend to ram realy slow (1-2 minutes)
Status: CLOSED PATCH_ALREADY_AVAILABLE
Alias: None
Product: ACPI
Classification: Unclassified
Component: Power-Sleep-Wake (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: ykzhao
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2008-09-03 11:28 UTC by Hannes Diethelm
Modified: 2008-10-27 19:23 UTC (History)
1 user (show)

See Also:
Kernel Version: 2.6.26.3
Subsystem:
Regression: ---
Bisected commit-id:


Attachments
Patch 1/4 : Don't issue the burst disable command if EC exits the burst mode (1.40 KB, patch)
2008-09-08 00:02 UTC, ykzhao
Details | Diff
Patch 2/4: Clear the query_pending bit only after processing EC notification event (2.21 KB, patch)
2008-09-08 00:17 UTC, ykzhao
Details | Diff
Patch 3/4: Simplify EC working flowchart and always enable EC GPE (6.84 KB, patch)
2008-09-08 00:19 UTC, ykzhao
Details | Diff
patch 4/4: Add some udelay in EC GPE handler to avoid EC GPE interrupt storm (4.92 KB, patch)
2008-09-08 00:21 UTC, ykzhao
Details | Diff
dmesg output of patched kernel after one suspend/resume (51.71 KB, text/plain)
2008-09-14 13:08 UTC, Hannes Diethelm
Details
acpidump of patched kernel before suspend/resume (159.93 KB, text/plain)
2008-09-14 13:09 UTC, Hannes Diethelm
Details
Quick and dirty backport to 2.6.26 of these four patches (9.02 KB, patch)
2008-09-14 14:54 UTC, Hannes Diethelm
Details | Diff
Patch 3/4: Switch to polling mode when there is no EC GPE interrupt for some EC transactions (4.38 KB, patch)
2008-09-16 18:44 UTC, ykzhao
Details | Diff
patch 4/4: Add some delay in EC GPE handler to avoid EC GPE storm (5.23 KB, patch)
2008-09-16 18:45 UTC, ykzhao
Details | Diff
dmesg output of patched kernel after one suspend/resume new patchset / 2.6.27-rc6 (51.61 KB, text/plain)
2008-09-17 06:23 UTC, Hannes Diethelm
Details
All configs and dmesg output of tested kernels (292.80 KB, application/x-gzip)
2008-09-19 01:56 UTC, Hannes Diethelm
Details
2.6.27-rc5 patchset 2 cmdline: hpet=disable idle=poll (62.67 KB, text/plain)
2008-09-23 04:43 UTC, Hannes Diethelm
Details
Dmesg output of test described in comment #26 (49.37 KB, application/octet-stream)
2008-09-25 23:29 UTC, Hannes Diethelm
Details
Dmesg output 2.6.27-rc7 with patch from http://bugzilla.kernel.org/show_bug.cgi?id=10724#C142 (52.04 KB, text/plain)
2008-10-20 02:31 UTC, Hannes Diethelm
Details

Description Hannes Diethelm 2008-09-03 11:28:56 UTC
Latest working kernel version: none
Earliest failing kernel version: all
Distribution: Debian
Hardware Environment: HP nx8220 py518ea
Software Environment: Debian Etch / Vanilla Kernel 2.6.26.3
Problem Description:

Hello!

Since i have this laptop, i have problems with suspend to ram. Some time ago, the laptop frezed any way while resuming. But now i'm able to resume but it's realy slow and i need to hit random keys to proceed resuming. I think it's because the kernel hangs somewhere and the keyboard interrupts are helping to get out of the loop. In single mode there is now resume at all, so unloading modules or starting without X11 is not an option.

Some times if i'm lucky my laptop hets out of suspend in abaut 10 seconds but only one in abaut 100 times...

I compiled the kernel with printk timestamps so you can see where it is hanging:
[  445.417910] ACPI: Preparing to enter system sleep state S3
[  445.418011] Intel machine check architecture supported.
[  445.418029] Intel machine check reporting enabled on CPU#0.
[  445.418125] Back to C!
[  445.418698] Force enabled HPET at resume
[  445.607616] ACPI: EC: non-query interrupt received, switching to interrupt mode
[  557.831800] ACPI: EC: acpi_ec_wait timeout, status = 0x09, event = "b0=1"
[  557.831805] ACPI: EC: read timeout, command = 130
[  557.979092] PM: Writing back config space on device 0000:00:01.0 at offset f (was 100, writing c010a)

Steps to reproduce: Run hibernate with sysfs-ram.conf on this laptop.

All dmesg output:

[    0.000000] Linux version 2.6.26.3-bootsplash (hannes@linux-laptop) (gcc version 4.1.2 20061115 (prerelease) (Debian 4.1.1-21)) #1 Wed Sep 3 18:35:56 CEST 2008
[    0.000000] PAT disabled. Not yet verified on this CPU type.
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[    0.000000]  BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000003ffd0000 (usable)
[    0.000000]  BIOS-e820: 000000003ffd0000 - 000000003ffefc00 (reserved)
[    0.000000]  BIOS-e820: 000000003ffefc00 - 000000003fffb000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000003fffb000 - 0000000040000000 (reserved)
[    0.000000]  BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 00000000fec02000 (reserved)
[    0.000000]  BIOS-e820: 00000000fed20000 - 00000000fed9b000 (reserved)
[    0.000000]  BIOS-e820: 00000000feda0000 - 00000000fedc0000 (reserved)
[    0.000000]  BIOS-e820: 00000000ffb00000 - 00000000ffc00000 (reserved)
[    0.000000]  BIOS-e820: 00000000fff00000 - 0000000100000000 (reserved)
[    0.000000] 127MB HIGHMEM available.
[    0.000000] 896MB LOWMEM available.
[    0.000000] Entering add_active_range(0, 0, 262096) 0 entries of 256 used
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA             0 ->     4096
[    0.000000]   Normal       4096 ->   229376
[    0.000000]   HighMem    229376 ->   262096
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[1] active PFN ranges
[    0.000000]     0:        0 ->   262096
[    0.000000] On node 0 totalpages: 262096
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 4064 pages, LIFO batch:0
[    0.000000]   Normal zone: 1760 pages used for memmap
[    0.000000]   Normal zone: 223520 pages, LIFO batch:31
[    0.000000]   HighMem zone: 256 pages used for memmap
[    0.000000]   HighMem zone: 32464 pages, LIFO batch:7
[    0.000000]   Movable zone: 0 pages used for memmap
[    0.000000] DMI 2.3 present.
[    0.000000] ACPI: RSDP 000F6EF0, 0014 (r0 HP    )
[    0.000000] ACPI: RSDT 3FFEFC84, 0034 (r1 HP     0934     11070720 HP          1)
[    0.000000] ACPI: FACP 3FFEFC00, 0084 (r2 HP     0934            2 HP          1)
[    0.000000] ACPI: DSDT 3FFEFD50, 84E7 (r1 HP       nc8200    10000 MSFT  100000E)
[    0.000000] ACPI: FACS 3FFFAE80, 0040
[    0.000000] ACPI: APIC 3FFEFCB8, 005A (r1 HP     0934            1 HP          1)
[    0.000000] ACPI: MCFG 3FFEFD14, 003C (r1 HP     0934            1 HP          1)
[    0.000000] ACPI: SSDT 3FFF8237, 0371 (r1 HP       HPQPpc     1001 MSFT  100000E)
[    0.000000] ACPI: DMI detected: Hewlett-Packard
[    0.000000] ACPI: PM-Timer IO Port: 0x1008
[    0.000000] ACPI: Local APIC address 0xfec01000
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] Allocating PCI resources starting at 50000000 (gap: 40000000:a0000000)
[    0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000e0000
[    0.000000] PM: Registered nosave memory: 00000000000e0000 - 0000000000100000
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 260048
[    0.000000] Kernel command line: root=/dev/hda2 ro vga=791 splash=verbose resume=swap:/dev/hda9 badram=0x0ad89698,0xfffffffc
[    0.000000] bootsplash: verbose mode.
[    0.000000] PAGE_OFFSET=0xc0000000
[    0.000000] BadRAM option is =0x0ad89698,0xfffffffc
[    0.000000]    --> marking 0x0ad89698, 0xfffffffc  [2]
[    0.000000] 000000000000ad89 =000000000000ad89
[    0.000000] mapped APIC to ffffb000 (fec01000)
[    0.000000] mapped IOAPIC to ffffa000 (fec00000)
[    0.000000] Enabling fast FPU save and restore... done.
[    0.000000] Enabling unmasked SIMD FPU exception support... done.
[    0.000000] Initializing CPU#0
[    0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes)
[    0.000000] Detected 2128.032 MHz processor.
[    0.000999] Console: colour dummy device 80x25
[    0.000999] console [tty0] enabled
[    0.000999] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000999] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000999] Memory: 1033628k/1048384k available (2510k kernel code, 13516k reserved, 1189k data, 232k init, 130880k highmem, 4k BadRAM)
[    0.000999] virtual kernel memory layout:
[    0.000999]     fixmap  : 0xfffa8000 - 0xfffff000   ( 348 kB)
[    0.000999]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[    0.000999]     vmalloc : 0xf8800000 - 0xff7fe000   ( 111 MB)
[    0.000999]     lowmem  : 0xc0000000 - 0xf8000000   ( 896 MB)
[    0.000999]       .init : 0xc04a0000 - 0xc04da000   ( 232 kB)
[    0.000999]       .data : 0xc03738c7 - 0xc049ce40   (1189 kB)
[    0.000999]       .text : 0xc0100000 - 0xc03738c7   (2510 kB)
[    0.000999] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000999] CPA: page pool initialized 1 of 1 pages preallocated
[    0.061057] Calibrating delay using timer specific routine.. 4258.61 BogoMIPS (lpj=2129307)
[    0.061096] Mount-cache hash table entries: 512
[    0.061204] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.061212] CPU: L2 cache: 2048K
[    0.061217] Intel machine check architecture supported.
[    0.061222] Intel machine check reporting enabled on CPU#0.
[    0.061231] CPU: Intel(R) Pentium(R) M processor 2.13GHz stepping 08
[    0.061239] Checking 'hlt' instruction... OK.
[    0.065385] Freeing SMP alternatives: 0k freed
[    0.065390] ACPI: Core revision 20080321
[    0.079215] ENABLING IO-APIC IRQs
[    0.079411] ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.089986] net_namespace: 636 bytes
[    0.089986] HP Compaq Laptop series board detected. Selecting BIOS-method for reboots.
[    0.089986] NET: Registered protocol family 16
[    0.089986] ACPI: bus type pci registered
[    0.089986] PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 255
[    0.089986] PCI: MCFG area at e0000000 reserved in E820
[    0.089986] PCI: Using MMCONFIG for extended config space
[    0.089986] PCI: Using configuration type 1 for base access
[    0.089986] Setting up standard PCI resources
[    0.091546] ACPI: EC: Look up EC in DSDT
[    0.094643] ACPI: EC: non-query interrupt received, switching to interrupt mode
[    0.119108] ACPI: Interpreter enabled
[    0.119114] ACPI: (supports S0 S3 S4 S5)
[    0.119128] ACPI: Using IOAPIC for interrupt routing
[    0.130099] ACPI: EC: GPE = 0x10, I/O: command/status = 0x66, data = 0x62
[    0.130110] ACPI: EC: driver started in interrupt mode
[    0.130157] ACPI: PCI Root Bridge [C003] (0000:00)
[    0.130852] pci 0000:00:1f.0: Force enabled HPET at 0xfed00000
[    0.130858] pci 0000:00:1f.0: quirk: region 1000-107f claimed by ICH6 ACPI/GPIO/TCO
[    0.130866] pci 0000:00:1f.0: quirk: region 1100-113f claimed by ICH6 GPIO
[    0.131807] PCI: Transparent bridge - 0000:00:1e.0
[    0.131865] ACPI: PCI Interrupt Routing Table [\_SB_.C003._PRT]
[    0.132177] ACPI: PCI Interrupt Routing Table [\_SB_.C003.C054._PRT]
[    0.132271] ACPI: PCI Interrupt Routing Table [\_SB_.C003.C069._PRT]
[    0.132470] ACPI: PCI Interrupt Routing Table [\_SB_.C003.C0CE._PRT]
[    0.132608] ACPI: PCI Interrupt Routing Table [\_SB_.C003.C0DE._PRT]
[    0.158661] ACPI: PCI Interrupt Link [C0DA] (IRQs *10 11)
[    0.158867] ACPI: PCI Interrupt Link [C0DB] (IRQs 10 *11)
[    0.159075] ACPI: PCI Interrupt Link [C0DC] (IRQs 10 *11)
[    0.159278] ACPI: PCI Interrupt Link [C0DD] (IRQs *10 11)
[    0.159480] ACPI: PCI Interrupt Link [C0F0] (IRQs *10 11)
[    0.159683] ACPI: PCI Interrupt Link [C0F1] (IRQs 10 *11)
[    0.159886] ACPI: PCI Interrupt Link [C0F2] (IRQs *10 11)
[    0.159984] ACPI Exception (pci_link-0184): AE_NOT_FOUND, Evaluating _PRS [20080321]
[    0.160166] ACPI: Power Resource [C1D0] (on)
[    0.160340] ACPI: Power Resource [C1B2] (on)
[    0.160502] ACPI: Power Resource [C1B9] (on)
[    0.160547] ACPI: Power Resource [C1C9] (on)
[    0.160647] ACPI: Power Resource [C251] (off)
[    0.160736] ACPI: Power Resource [C252] (off)
[    0.160825] ACPI: Power Resource [C253] (off)
[    0.160913] ACPI: Power Resource [C254] (off)
[    0.160982] Linux Plug and Play Support v0.97 (c) Adam Belay
[    0.161010] pnp: PnP ACPI init
[    0.161018] ACPI: bus type pnp registered
[    0.172075] pnp: PnP ACPI: found 13 devices
[    0.172084] ACPI: ACPI bus type pnp unregistered
[    0.172260] SCSI subsystem initialized
[    0.172350] usbcore: registered new interface driver usbfs
[    0.172401] usbcore: registered new interface driver hub
[    0.172437] usbcore: registered new device driver usb
[    0.172592] PCI: Using ACPI for IRQ routing
[    0.172735] NET: Registered protocol family 8
[    0.172740] NET: Registered protocol family 20
[    0.172875] hpet clockevent registered
[    0.172881] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.172888] hpet0: 3 64-bit timers, 14318180 Hz
[    0.173933] system 00:00: iomem range 0x0-0x9ffff could not be reserved
[    0.173939] system 00:00: iomem range 0xe0000-0xfffff could not be reserved
[    0.173945] system 00:00: iomem range 0x100000-0x3fffffff could not be reserved
[    0.173957] system 00:0a: ioport range 0x500-0x57f has been reserved
[    0.173963] system 00:0a: iomem range 0xffb00000-0xffbfffff could not be reserved
[    0.173970] system 00:0a: iomem range 0xfff00000-0xffffffff could not be reserved
[    0.173980] system 00:0b: ioport range 0x4d0-0x4d1 has been reserved
[    0.173985] system 00:0b: ioport range 0x1000-0x107f has been reserved
[    0.173990] system 00:0b: ioport range 0x1100-0x113f has been reserved
[    0.173996] system 00:0b: ioport range 0x1200-0x121f has been reserved
[    0.174001] system 00:0b: iomem range 0xe0000000-0xefffffff could not be reserved
[    0.174008] system 00:0b: iomem range 0xfec00000-0xfec000ff could not be reserved
[    0.174014] system 00:0b: iomem range 0xfed20000-0xfed8ffff could not be reserved
[    0.174021] system 00:0b: iomem range 0xfed90000-0xfed9afff could not be reserved
[    0.174029] system 00:0c: iomem range 0xfeda0000-0xfedbffff could not be reserved
[    0.174035] system 00:0c: iomem range 0xfec01000-0xfec01fff could not be reserved
[    0.204348] PCI: region 0000:02:06.0/9 too large: 0x0000000000000000-0x0000000003ffffff
[    0.204359] PCI: Bridge: 0000:00:01.0
[    0.204363]   IO window: 2000-2fff
[    0.204368]   MEM window: 0xc8800000-0xc8bfffff
[    0.204373]   PREFETCH window: 0x00000000c0000000-0x00000000c7ffffff
[    0.204380] PCI: Bridge: 0000:00:1c.0
[    0.204383]   IO window: disabled.
[    0.204390]   MEM window: 0xc8000000-0xc83fffff
[    0.204396]   PREFETCH window: disabled.
[    0.204405] PCI: Bridge: 0000:00:1c.1
[    0.204408]   IO window: disabled.
[    0.204415]   MEM window: disabled.
[    0.204420]   PREFETCH window: disabled.
[    0.204430] PCI: Bus 3, cardbus bridge: 0000:02:06.0
[    0.204435]   IO window: 0x00004000-0x000040ff
[    0.204442]   IO window: 0x00004400-0x000044ff
[    0.204449]   MEM window: 0x50000000-0x53ffffff
[    0.204456] PCI: Bridge: 0000:00:1e.0
[    0.204461]   IO window: 4000-4fff
[    0.204468]   MEM window: 0xc8400000-0xc87fffff
[    0.204474]   PREFETCH window: disabled.
[    0.204492] ACPI: PCI Interrupt 0000:00:01.0[A] -> GSI 16 (level, low) -> IRQ 16
[    0.204501] PCI: Setting latency timer of device 0000:00:01.0 to 64
[    0.204520] ACPI: PCI Interrupt 0000:00:1c.0[A] -> GSI 16 (level, low) -> IRQ 16
[    0.204529] PCI: Setting latency timer of device 0000:00:1c.0 to 64
[    0.204549] ACPI: PCI Interrupt 0000:00:1c.1[B] -> GSI 17 (level, low) -> IRQ 17
[    0.204558] PCI: Setting latency timer of device 0000:00:1c.1 to 64
[    0.204570] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[    0.204587] ACPI: PCI Interrupt 0000:02:06.0[A] -> GSI 18 (level, low) -> IRQ 18
[    0.204613] NET: Registered protocol family 2
[    0.204683] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.204866] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.205473] TCP bind hash table entries: 65536 (order: 6, 262144 bytes)
[    0.205652] TCP: Hash tables configured (established 131072 bind 65536)
[    0.205657] TCP reno registered
[    0.205724] NET: Registered protocol family 1
[    0.205815] checking if image is initramfs...it isn't (bad gzip magic numbers); looks like an initrd
[    0.206023] Freeing initrd memory: 108k freed
[    0.206368] audit: initializing netlink socket (disabled)
[    0.206381] type=2000 audit(1220461093.205:1): initialized
[    0.206502] highmem bounce pool size: 64 pages
[    0.206563] VFS: Disk quotas dquot_6.5.1
[    0.206588] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.206659] msgmni has been set to 1765
[    0.206707] io scheduler noop registered
[    0.206712] io scheduler anticipatory registered
[    0.206716] io scheduler deadline registered
[    0.206728] io scheduler cfq registered (default)
[    0.206804] pci 0000:01:00.0: Boot video device
[    0.206887] PCI: Setting latency timer of device 0000:00:01.0 to 64
[    0.206911] assign_interrupt_mode Found MSI capability
[    0.206935] Allocate Port Service[0000:00:01.0:pcie00]
[    0.206973] Allocate Port Service[0000:00:01.0:pcie03]
[    0.207033] PCI: Setting latency timer of device 0000:00:1c.0 to 64
[    0.207073] assign_interrupt_mode Found MSI capability
[    0.207114] Allocate Port Service[0000:00:1c.0:pcie00]
[    0.207143] Allocate Port Service[0000:00:1c.0:pcie02]
[    0.207169] Allocate Port Service[0000:00:1c.0:pcie03]
[    0.207252] PCI: Setting latency timer of device 0000:00:1c.1 to 64
[    0.207292] assign_interrupt_mode Found MSI capability
[    0.207333] Allocate Port Service[0000:00:1c.1:pcie00]
[    0.207360] Allocate Port Service[0000:00:1c.1:pcie02]
[    0.207387] Allocate Port Service[0000:00:1c.1:pcie03]
[    0.207707] vesafb: framebuffer at 0xc0000000, mapped to 0xf8880000, using 6144k, total 131008k
[    0.207714] vesafb: mode is 1024x768x16, linelength=2048, pages=84
[    0.207719] vesafb: protected mode interface info at c000:5af5
[    0.207724] vesafb: pmi: set display start = c00c5b63, set palette = c00c5b9d
[    0.207729] vesafb: pmi: ports = 2010 2016 2054 2038 203c 205c 2000 2004 20b0 20b2 20b4 
[    0.207740] vesafb: scrolling: redraw
[    0.207744] vesafb: Truecolor: size=0:5:6:5, shift=0:11:5:0
[    0.207929] bootsplash 3.1.6-2004/03/31: looking for picture...<6> silentjpeg size 44224 bytes,<6>...found (1024x768, 67122 bytes, v3).
[    0.224389] Console: switching to colour frame buffer device 128x48
[    0.261627] fb0: VESA VGA frame buffer device
[    0.264386] ACPI: AC Adapter [C176] (on-line)
[    0.324739] ACPI: Battery Slot [C178] (battery present)
[    0.325143] ACPI: Battery Slot [C177] (battery absent)
[    0.325859] input: Power Button (FF) as /class/input/input0
[    0.326084] ACPI: Power Button (FF) [PWRF]
[    0.326317] input: Sleep Button (CM) as /class/input/input1
[    0.326539] ACPI: Sleep Button (CM) [C1F2]
[    0.326753] input: Lid Switch as /class/input/input2
[    0.327145] ACPI: Lid Switch [C1F3]
[    0.327503] ACPI: Transitioning device [C255] to D3
[    0.327728] ACPI: PNP0C0B:00 is registered as cooling_device0
[    0.327951] ACPI: Fan [C255] (off)
[    0.328261] ACPI: Transitioning device [C256] to D3
[    0.328481] ACPI: PNP0C0B:01 is registered as cooling_device1
[    0.328713] ACPI: Fan [C256] (off)
[    0.328964] ACPI: Transitioning device [C257] to D3
[    0.329185] ACPI: PNP0C0B:02 is registered as cooling_device2
[    0.329416] ACPI: Fan [C257] (off)
[    0.329719] ACPI: Transitioning device [C258] to D3
[    0.329939] ACPI: PNP0C0B:03 is registered as cooling_device3
[    0.330174] ACPI: Fan [C258] (off)
[    0.330706] ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3])
[    0.338390] ACPI: ACPI0007:00 is registered as cooling_device4
[    0.346021] ACPI: Processor [C001] (supports 8 throttling states)
[    0.410122] ACPI: LNXTHERM:01 is registered as thermal_zone0
[    0.439214] ACPI: Thermal Zone [TZ1] (56 C)
[    0.470609] ACPI: LNXTHERM:02 is registered as thermal_zone1
[    0.487608] ACPI: Thermal Zone [TZ2] (44 C)
[    0.501901] ACPI: LNXTHERM:03 is registered as thermal_zone2
[    0.515453] ACPI: Thermal Zone [TZ3] (30 C)
[    0.525607] ACPI: LNXTHERM:04 is registered as thermal_zone3
[    0.536604] ACPI: Thermal Zone [TZ4] (40 C)
[    0.561142] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[    0.570605] serial8250: ttyS2 at I/O 0x3e8 (irq = 4) is a 16550A
[    0.579262] ACPI: PCI Interrupt 0000:00:1e.3[B] -> GSI 22 (level, low) -> IRQ 22
[    0.587036] ACPI: PCI interrupt for device 0000:00:1e.3 disabled
[    0.596606] brd: module loaded
[    0.604262] Uniform Multi-Platform E-IDE driver
[    0.612370] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[    0.620751] ICH6: IDE controller (0x8086:0x266f rev 0x03) at  PCI slot 0000:00:1f.1
[    0.629170] ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 16 (level, low) -> IRQ 16
[    0.637562] ICH6: not 100% native mode: will probe irqs later
[    0.645969] ICH6: IDE port disabled
[    0.654288]     ide0: BM-DMA at 0x3580-0x3587
[    0.662542] Probing IDE interface ide0...
[    0.834606] Switched to high resolution mode on CPU 0
[    0.995574] hda: HTS541080G9AT00, ATA DISK drive
[    1.300992] hdb: MATSHITADVD-RAM UJ-832S, ATAPI CD/DVD-ROM drive
[    1.360441] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[    1.360441] hda: UDMA/100 mode selected
[    1.368591] hdb: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[    1.368864] hdb: MWDMA2 mode selected
[    1.378000] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[    1.403100] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports
[    1.411606] ide_generic: I/O resource 0x1F0-0x1F7 not free.
[    1.419996] ide_generic: I/O resource 0x170-0x177 not free.
[    1.429037] hda: max request size: 128KiB
[    1.437460] hda: 156301488 sectors (80026 MB) w/7539KiB Cache, CHS=65535/16/63
[    1.446182] hda: cache flushes supported
[    1.454239]  hda: hda1 hda2 hda3 < hda5 hda6 hda7 hda8 hda9 hda10 >
[    1.551745] hdb: ATAPI 24X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache
[    1.560348] Uniform CD-ROM driver Revision: 3.20
[    1.570844] ACPI: PCI Interrupt 0000:00:1d.7[A] -> GSI 20 (level, low) -> IRQ 20
[    1.580106] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[    1.580110] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[    1.588878] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
[    1.602001] ehci_hcd 0000:00:1d.7: debug port 1
[    1.610708] PCI: cache line size of 32 is not supported by device 0000:00:1d.7
[    1.610718] ehci_hcd 0000:00:1d.7: irq 20, io mem 0xc8c00000
[    1.628072] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[    1.636978] usb usb1: configuration #1 chosen from 1 choice
[    1.645855] hub 1-0:1.0: USB hub found
[    1.654743] hub 1-0:1.0: 8 ports detected
[    1.764303] USB Universal Host Controller Interface driver v3.0
[    1.773085] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 20 (level, low) -> IRQ 20
[    1.782082] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[    1.782086] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[    1.791124] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    1.800177] uhci_hcd 0000:00:1d.0: irq 20, io base 0x00003000
[    1.809221] usb usb2: configuration #1 chosen from 1 choice
[    1.818230] hub 2-0:1.0: USB hub found
[    1.827155] hub 2-0:1.0: 2 ports detected
[    1.936138] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 17 (level, low) -> IRQ 17
[    1.945178] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[    1.945182] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[    1.954232] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[    1.963348] uhci_hcd 0000:00:1d.1: irq 17, io base 0x00003020
[    1.972505] usb usb3: configuration #1 chosen from 1 choice
[    1.981513] hub 3-0:1.0: USB hub found
[    1.990476] hub 3-0:1.0: 2 ports detected
[    2.100154] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
[    2.109045] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[    2.109049] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[    2.117917] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[    2.126902] uhci_hcd 0000:00:1d.2: irq 18, io base 0x00003040
[    2.135952] usb usb4: configuration #1 chosen from 1 choice
[    2.144688] hub 4-0:1.0: USB hub found
[    2.153217] hub 4-0:1.0: 2 ports detected
[    2.262739] Initializing USB Mass Storage driver...
[    2.321593] usb 3-2: new full speed USB device using uhci_hcd and address 2
[    2.461999] usb 3-2: configuration #1 chosen from 1 choice
[    2.493392] usbcore: registered new interface driver usb-storage
[    2.502516] USB Mass Storage support registered.
[    2.511598] PNP: PS/2 Controller [PNP0303:C1C6,PNP0f13:C1C7] at 0x60,0x64 irq 1,12
[    2.521124] i8042.c: Detected active multiplexing controller, rev 1.1.
[    2.532017] serio: i8042 KBD port at 0x60,0x64 irq 1
[    2.540753] serio: i8042 AUX0 port at 0x60,0x64 irq 12
[    2.549208] serio: i8042 AUX1 port at 0x60,0x64 irq 12
[    2.557807] serio: i8042 AUX2 port at 0x60,0x64 irq 12
[    2.566253] serio: i8042 AUX3 port at 0x60,0x64 irq 12
[    2.575002] mice: PS/2 mouse device common for all mice
[    2.583374] cpuidle: using governor ladder
[    2.592001] cpuidle: using governor menu
[    2.600045] usbcore: registered new interface driver hiddev
[    2.608095] usbcore: registered new interface driver usbhid
[    2.615910] usbhid: v2.6:USB HID core driver
[    2.623094] Advanced Linux Sound Architecture Driver Version 1.0.16.
[    2.631102] ALSA device list:
[    2.638823]   No soundcards found.
[    2.646833] TCP bic registered
[    2.654464] NET: Registered protocol family 17
[    2.662064] Using IPI Shortcut mode
[    2.669844] TuxOnIce 3.0-rc7 (http://tuxonice.net)
[    2.680011] Marking TSC unstable due to: TSC halts in idle.
[    2.685011] input: AT Translated Set 2 keyboard as /class/input/input3
[    2.778090] RAMDISK: Couldn't find valid RAM disk image starting at 0.
[    2.785740] Replacing swsusp.
[    2.793856] TuxOnIce: Normal swapspace found.
[    2.821406] kjournald starting.  Commit interval 5 seconds
[    2.828456] EXT3-fs: mounted filesystem with ordered data mode.
[    2.836454] VFS: Mounted root (ext3 filesystem) readonly.
[    2.844170] Freeing unused kernel memory: 232k freed
[    3.172022] Clocksource tsc unstable (delta = -82231112 ns)
[    4.796287] input: PC Speaker as /class/input/input4
[    5.166885] Real Time Clock Driver v1.12ac
[    5.449960] irda_init()
[    5.449968] NET: Registered protocol family 23
[    5.485955] ACPI: PCI Interrupt 0000:00:1e.3[B] -> GSI 22 (level, low) -> IRQ 22
[    5.494482] PCI: Setting latency timer of device 0000:00:1e.3 to 64
[    5.537139] Bluetooth: Core ver 2.11
[    5.546494] NET: Registered protocol family 31
[    5.554569] Bluetooth: HCI device and connection manager initialized
[    5.562593] Bluetooth: HCI socket layer initialized
[    5.573387] tg3.c:v3.92.1 (June 9, 2008)
[    5.583380] ACPI: PCI Interrupt 0000:10:00.0[A] -> GSI 16 (level, low) -> IRQ 16
[    5.591741] PCI: Setting latency timer of device 0000:10:00.0 to 64
[    5.646569] eth0: Tigon3 [partno(BCM95751M) rev 4101 PHY(5750)] (PCI Express) 10/100/1000Base-T Ethernet 00:14:c2:e7:1c:8d
[    5.655343] eth0: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[0] WireSpeed[1] TSOcap[1]
[    5.663935] eth0: dma_rwctrl[76180000] dma_mask[64-bit]
[    5.673153] ACPI: PCI Interrupt 0000:00:1e.2[A] -> GSI 21 (level, low) -> IRQ 21
[    5.681789] PCI: Setting latency timer of device 0000:00:1e.2 to 64
[    5.746420] Bluetooth: HCI USB driver ver 2.9
[    5.756775] usbcore: registered new interface driver hci_usb
[    5.840602] Linux agpgart interface v0.103
[    5.873727] ieee80211_crypt: registered algorithm 'NULL'
[    5.896373] sdhci: Secure Digital Host Controller Interface driver
[    5.905129] sdhci: Copyright(c) Pierre Ossman
[    5.916020] ieee80211: 802.11 data/management/control stack, git-1.1.13
[    5.924981] ieee80211: Copyright (C) 2004-2005 Intel Corporation <jketreno@linux.intel.com>
[    5.997670] ipw2200: Intel(R) PRO/Wireless 2200/2915 Network Driver, 1.2.2kmq
[    6.007151] ipw2200: Copyright(c) 2003-2006 Intel Corporation
[    6.036103] AC'97 0 analog subsections not ready
[    6.057456] Synaptics Touchpad, model: 1, fw: 6.2, id: 0x25a0b1, caps: 0xa04793/0x300000
[    6.067004] serio: Synaptics pass-through port at isa0060/serio4/input0
[    6.117121] input: SynPS/2 Synaptics TouchPad as /class/input/input5
[    6.608028] intel8x0_measure_ac97_clock: measured 50884 usecs
[    6.618071] intel8x0: measured clock 216 rejected
[    6.627700] intel8x0: clocking to 48000
[    6.638942] Yenta: CardBus bridge found at 0000:02:06.0 [103c:0934]
[    6.648849] PCI: Bus 3, cardbus bridge: 0000:02:06.0
[    6.658534]   IO window: 0x00004000-0x000040ff
[    6.668328]   IO window: 0x00004400-0x000044ff
[    6.677966]   PREFETCH window: 0x54000000-0x543fffff
[    6.687737]   MEM window: 0x50000000-0x53ffffff
[    6.697302] Yenta: Enabling burst memory read transactions
[    6.706941] Yenta: Using INTVAL to route CSC interrupts to PCI
[    6.716486] Yenta: Routing CardBus interrupts to PCI
[    6.726019] Yenta TI: socket 0000:02:06.0, mfunc 0x01111b22, devctl 0x64
[    6.959850] Yenta: ISA IRQ mask 0x0cf8, PCI irq 18
[    6.969797] Socket status: 30000006
[    6.979795] Yenta: Raising subordinate bus# of parent bus (#02) from #03 to #06
[    6.989997] pcmcia: parent PCI bridge I/O window: 0x4000 - 0x4fff
[    6.999006] pcmcia: parent PCI bridge Memory window: 0xc8400000 - 0xc87fffff
[    7.009084] ACPI: PCI Interrupt 0000:02:06.2[C] -> GSI 22 (level, low) -> IRQ 22
[    7.069003] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[22]  MMIO=[c8402000-c84027ff]  Max Packet=[2048]  IR/IT contexts=[4/8]
[    7.092581] ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 21 (level, low) -> IRQ 21
[    7.104004] ipw2200: Detected Intel PRO/Wireless 2200BG Network Connection
[    7.114148] firmware: requesting ipw2200-bss.fw
[    7.147563] ipw2200: ipw2200-bss.fw request_firmware failed: Reason -2
[    7.158193] ipw2200: Unable to load firmware: -2
[    7.169442] ipw2200: failed to register network device
[    7.179695] ACPI: PCI interrupt for device 0000:02:04.0 disabled
[    7.189781] ipw2200: probe of 0000:02:04.0 failed with error -5
[    7.201005] ACPI: PCI Interrupt 0000:02:06.3[B] -> GSI 19 (level, low) -> IRQ 19
[    7.211129] sdhci: SDHCI controller found at 0000:02:06.4 [104c:8034] (rev 0)
[    7.221181] ACPI: PCI Interrupt 0000:02:06.4[C] -> GSI 22 (level, low) -> IRQ 22
[    7.231225] mmc0: Will use DMA mode even though HW doesn't fully claim to support it.
[    7.241177] mmc0: SDHCI at 0xc840a000 irq 22 DMA
[    7.251850] mmc1: Will use DMA mode even though HW doesn't fully claim to support it.
[    7.262211] mmc1: SDHCI at 0xc840b000 irq 22 DMA
[    7.272844] mmc2: Will use DMA mode even though HW doesn't fully claim to support it.
[    7.284019] mmc2: SDHCI at 0xc840c000 irq 22 DMA
[    7.693800] Adding 979924k swap on /dev/hda9.  Priority:-1 extents:1 across:979924k
[    7.951113] EXT3 FS on hda2, internal journal
[    8.344161] ieee1394: Host added: ID:BUS[0-00:1023]  GUID[a5385a4700508b71]
[    8.972282] loop: disagrees about version of symbol contig_page_data
[    8.982414] loop: Unknown symbol contig_page_data
[    8.991856] loop: disagrees about version of symbol __alloc_pages
[    9.001742] loop: Unknown symbol __alloc_pages
[    9.079895] kqemu: module license 'Proprietary' taints kernel.
[    9.091824] QEMU Accelerator Module version 1.3.0, Copyright (c) 2005-2006 Fabrice Bellard
[    9.091826] This is a proprietary product. Read the LICENSE file for more information
[    9.091828] Redistribution of this module is prohibited without authorization
[    9.121766] KQEMU installed, max_locked_mem=517408kB.
[  221.735655] fuse init (API version 7.9)
[  221.809681] kjournald starting.  Commit interval 5 seconds
[  221.819915] EXT3 FS on hda5, internal journal
[  221.830052] EXT3-fs: mounted filesystem with ordered data mode.
[  221.855804] kjournald starting.  Commit interval 5 seconds
[  221.865773] EXT3 FS on hda10, internal journal
[  221.875578] EXT3-fs: mounted filesystem with ordered data mode.
[  221.887711] kjournald starting.  Commit interval 5 seconds
[  221.898016] EXT3 FS on hda8, internal journal
[  221.908061] EXT3-fs: mounted filesystem with ordered data mode.
[  221.919110] kjournald starting.  Commit interval 5 seconds
[  221.929216] EXT3 FS on hda6, internal journal
[  221.939237] EXT3-fs: mounted filesystem with ordered data mode.
[  221.950726] kjournald starting.  Commit interval 5 seconds
[  221.961251] EXT3 FS on hda7, internal journal
[  221.971678] EXT3-fs: mounted filesystem with ordered data mode.
[  222.034164] NTFS driver 2.1.29 [Flags: R/O MODULE].
[  222.105833] NTFS volume version 3.1.
[  222.783303] pcmcia: Detected deprecated PCMCIA ioctl usage from process: discover.
[  222.793959] pcmcia: This interface will soon be removed from the kernel; please expect breakage unless you upgrade to new tools.
[  222.814955] pcmcia: see http://www.kernel.org/pub/linux/utils/kernel/pcmcia/pcmcia.html for details.
[  223.843984] ipw2200: Intel(R) PRO/Wireless 2200/2915 Network Driver, 1.2.2kmq
[  223.853872] ipw2200: Copyright(c) 2003-2006 Intel Corporation
[  223.864565] ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 21 (level, low) -> IRQ 21
[  223.874866] ipw2200: Detected Intel PRO/Wireless 2200BG Network Connection
[  223.884050] firmware: requesting ipw2200-bss.fw
[  224.201872] ipw2200: Detected geography ZZR (14 802.11bg channels, 0 802.11a channels)
[  228.317205] bootsplash 3.1.6-2004/03/31: looking for picture...<6>...found (1024x768, 67122 bytes, v3).
[  228.333469] bootsplash: status on console 1 changed to on
[  228.363509] bootsplash 3.1.6-2004/03/31: looking for picture...<6>...found (1024x768, 67122 bytes, v3).
[  228.379790] bootsplash: status on console 2 changed to on
[  228.401510] bootsplash 3.1.6-2004/03/31: looking for picture...<6>...found (1024x768, 67122 bytes, v3).
[  228.417819] bootsplash: status on console 3 changed to on
[  228.439511] bootsplash 3.1.6-2004/03/31: looking for picture...<6>...found (1024x768, 67122 bytes, v3).
[  228.455856] bootsplash: status on console 4 changed to on
[  228.478006] bootsplash 3.1.6-2004/03/31: looking for picture...<6>...found (1024x768, 67122 bytes, v3).
[  228.494437] bootsplash: status on console 5 changed to on
[  243.647404] warning: `avahi-daemon' uses 32-bit capabilities (legacy support in use)
[  244.590659] RPC: Registered udp transport module.
[  244.600685] RPC: Registered tcp transport module.
[  244.721666] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[  244.878705] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[  244.904702] NFSD: starting 90-second grace period
[  246.348288] NET: Registered protocol family 10
[  246.360004] lo: Disabled Privacy Extensions
[  246.808513] cdrom: This disc doesn't have any tracks I recognize!
[  246.825778] pktcdvd: writer pktcdvd0 mapped to hdb
[  252.855558] Symbol init_mm is marked as UNUSED, however this module is using it.
[  252.855578] This symbol will go away in the future.
[  252.855583] Please evalute if this is the right api to use and if it really is, submit a report the linux kernel mailinglist together with submitting your code for inclusion.
[  252.880272] [fglrx] Maximum main memory to use for locked dma buffers: 928 MBytes.
[  252.880561] [fglrx]   vendor: 1002 device: 3150 count: 1
[  252.880895] [fglrx] ioport: bar 1, base 0x2000, size: 0x100
[  252.880956] ACPI: PCI Interrupt 0000:01:00.0[A] -> GSI 16 (level, low) -> IRQ 16
[  252.880964] PCI: Setting latency timer of device 0000:01:00.0 to 64
[  252.881753] [fglrx] PAT is enabled successfully!
[  252.881905] [fglrx] module loaded - fglrx 8.52.3 [Aug  1 2008] with 1 minors
[  256.761436] Bluetooth: L2CAP ver 2.9
[  256.761442] Bluetooth: L2CAP socket layer initialized
[  256.807870] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[  257.133683] Bluetooth: RFCOMM socket layer initialized
[  257.133869] Bluetooth: RFCOMM TTY layer initialized
[  257.133878] Bluetooth: RFCOMM ver 1.8
[  257.358753] [fglrx] Reserved FB block: Shared offset:0, size:40000 
[  257.358761] [fglrx] Reserved FB block: Unshared offset:7fae000, size:40000 
[  257.358763] [fglrx] Reserved FB block: Unshared offset:7fee000, size:1000 
[  257.358765] [fglrx] Reserved FB block: Unshared offset:7fef000, size:1000 
[  257.358768] [fglrx] Reserved FB block: Unshared offset:7ff0000, size:10000 
[  258.637664] ADDRCONF(NETDEV_UP): eth0: link is not ready
[  264.655346] ieee80211_crypt: registered algorithm 'CCMP'
[  265.677723] ieee80211_crypt: registered algorithm 'TKIP'
[  273.518064] eth2: no IPv6 routers present
[  442.616123] ACPI: PCI interrupt for device 0000:02:04.0 disabled
[  443.169822] PM: Syncing filesystems ... done.
[  444.268217] Stopping fuse filesystems.
[  444.268377] Fuse filesystem done.
[  444.268514] Freezing user space processes ... (elapsed 0.06 seconds) <6>done.
[  444.336558] Stopping normal filesystems.
[  444.344068] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[  444.344068] Suspending console(s)
[  445.294103] ACPI handle has no context!
[  445.294507] ACPI handle has no context!
[  445.294535] ACPI: PCI interrupt for device 0000:02:06.4 disabled
[  445.294545] ACPI handle has no context!
[  445.305131] ACPI handle has no context!
[  445.305151] ACPI: PCI interrupt for device 0000:02:06.3 disabled
[  445.305160] ACPI handle has no context!
[  445.316143] ACPI handle has no context!
[  445.327311] [fglrx] Power down the ASIC .
[  445.379046] ACPI: PCI interrupt for device 0000:01:00.0 disabled
[  445.379364] ACPI: PCI interrupt for device 0000:00:1e.3 disabled
[  445.390243] codec_write 0: semaphore is not ready for register 0x26
[  445.390346] codec_write 0: semaphore is not ready for register 0x26
[  445.390355] ACPI: PCI interrupt for device 0000:00:1e.2 disabled
[  445.390459] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
[  445.401122] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
[  445.401158] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
[  445.401195] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
[  445.417910] ACPI: Preparing to enter system sleep state S3
[  445.418011] Intel machine check architecture supported.
[  445.418029] Intel machine check reporting enabled on CPU#0.
[  445.418125] Back to C!
[  445.418698] Force enabled HPET at resume
[  445.607616] ACPI: EC: non-query interrupt received, switching to interrupt mode
[  557.831800] ACPI: EC: acpi_ec_wait timeout, status = 0x09, event = "b0=1"
[  557.831805] ACPI: EC: read timeout, command = 130
[  557.979092] PM: Writing back config space on device 0000:00:01.0 at offset f (was 100, writing c010a)
[  557.979092] PM: Writing back config space on device 0000:00:01.0 at offset 3 (was 10000, writing 10010)
[  557.979092] PCI: Setting latency timer of device 0000:00:01.0 to 64
[  557.979092] PM: Writing back config space on device 0000:00:1c.0 at offset f (was 100, writing 4010a)
[  557.979092] PM: Writing back config space on device 0000:00:1c.0 at offset 9 (was 10001, writing 1fff1)
[  557.979092] PM: Writing back config space on device 0000:00:1c.0 at offset 8 (was 0, writing c830c800)
[  557.979092] PM: Writing back config space on device 0000:00:1c.0 at offset 7 (was 0, writing 200000f0)
[  557.979092] PM: Writing back config space on device 0000:00:1c.0 at offset 3 (was 810000, writing 810010)
[  557.979092] PM: Writing back config space on device 0000:00:1c.0 at offset 1 (was 100000, writing 100407)
[  557.979092] PCI: Setting latency timer of device 0000:00:1c.0 to 64
[  557.979092] PM: Writing back config space on device 0000:00:1c.1 at offset f (was 200, writing 4020b)
[  557.979092] PM: Writing back config space on device 0000:00:1c.1 at offset 9 (was 10001, writing 1fff1)
[  557.979092] PM: Writing back config space on device 0000:00:1c.1 at offset 8 (was 0, writing fff0)
[  557.979092] PM: Writing back config space on device 0000:00:1c.1 at offset 7 (was 20000000, writing 200000f0)
[  557.979092] PM: Writing back config space on device 0000:00:1c.1 at offset 3 (was 810000, writing 810010)
[  557.979092] PM: Writing back config space on device 0000:00:1c.1 at offset 1 (was 100000, writing 100407)
[  557.979092] PCI: Setting latency timer of device 0000:00:1c.1 to 64
[  557.979092] uhci_hcd 0000:00:1d.0: enabling device (0000 -> 0001)
[  557.979092] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 20 (level, low) -> IRQ 20
[  557.979092] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[  557.979092] PM: Writing back config space on device 0000:00:1d.0 at offset f (was 100, writing 10a)
[  557.979092] PM: Writing back config space on device 0000:00:1d.0 at offset 8 (was 1, writing 3001)
[  557.979092] usb usb2: root hub lost power or was reset
[  557.979092] uhci_hcd 0000:00:1d.1: enabling device (0000 -> 0001)
[  557.979092] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 17 (level, low) -> IRQ 17
[  557.979092] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[  557.979092] PM: Writing back config space on device 0000:00:1d.1 at offset f (was 200, writing 20b)
[  557.979092] PM: Writing back config space on device 0000:00:1d.1 at offset 8 (was 1, writing 3021)
[  557.979092] usb usb3: root hub lost power or was reset
[  557.979092] uhci_hcd 0000:00:1d.2: enabling device (0000 -> 0001)
[  557.979092] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
[  557.979092] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[  557.979092] PM: Writing back config space on device 0000:00:1d.2 at offset f (was 300, writing 30b)
[  557.979092] PM: Writing back config space on device 0000:00:1d.2 at offset 8 (was 1, writing 3041)
[  557.979092] usb usb4: root hub lost power or was reset
[  558.049185] ehci_hcd 0000:00:1d.7: enabling device (0000 -> 0002)
[  558.049185] ACPI: PCI Interrupt 0000:00:1d.7[A] -> GSI 20 (level, low) -> IRQ 20
[  558.049185] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[  558.049185] PM: Writing back config space on device 0000:00:1d.7 at offset f (was 100, writing 10a)
[  558.049185] PM: Writing back config space on device 0000:00:1d.7 at offset 4 (was 0, writing c8c00000)
[  558.049185] PM: Writing back config space on device 0000:00:1e.0 at offset 9 (was 10001, writing 1fff1)
[  558.049185] PM: Writing back config space on device 0000:00:1e.0 at offset 8 (was 0, writing c870c840)
[  558.049185] PM: Writing back config space on device 0000:00:1e.0 at offset 7 (was 22800010, writing 22804040)
[  558.049185] PM: Writing back config space on device 0000:00:1e.0 at offset 1 (was 100001, writing 100107)
[  558.049185] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[  558.049185] PM: Writing back config space on device 0000:00:1e.2 at offset f (was 100, writing 10b)
[  558.049185] PM: Writing back config space on device 0000:00:1e.2 at offset 7 (was 0, writing c8c02000)
[  558.049185] PM: Writing back config space on device 0000:00:1e.2 at offset 6 (was 0, writing c8c01000)
[  558.049185] PM: Writing back config space on device 0000:00:1e.2 at offset 5 (was 1, writing 3201)
[  558.049185] PM: Writing back config space on device 0000:00:1e.2 at offset 4 (was 1, writing 3101)
[  558.049185] PM: Writing back config space on device 0000:00:1e.2 at offset 1 (was 2900000, writing 2900003)
[  558.049185] ACPI: PCI Interrupt 0000:00:1e.2[A] -> GSI 21 (level, low) -> IRQ 21
[  558.049185] PCI: Setting latency timer of device 0000:00:1e.2 to 64
[  559.878259] PM: Writing back config space on device 0000:00:1e.3 at offset f (was 200, writing 20a)
[  559.878259] PM: Writing back config space on device 0000:00:1e.3 at offset 5 (was 1, writing 3501)
[  559.878259] PM: Writing back config space on device 0000:00:1e.3 at offset 4 (was 1, writing 3401)
[  559.878259] PM: Writing back config space on device 0000:00:1e.3 at offset 1 (was 2900000, writing 2900001)
[  559.878259] ACPI: PCI Interrupt 0000:00:1e.3[B] -> GSI 22 (level, low) -> IRQ 22
[  559.878259] PCI: Setting latency timer of device 0000:00:1e.3 to 64
[  560.914377] PM: Writing back config space on device 0000:00:1f.1 at offset f (was 100, writing 10a)
[  560.914395] PM: Writing back config space on device 0000:00:1f.1 at offset 8 (was 1, writing 3581)
[  560.914412] PM: Writing back config space on device 0000:00:1f.1 at offset 1 (was 2800000, writing 2800005)
[  560.914426] ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 16 (level, low) -> IRQ 16
[  560.915006] ACPI: PCI Interrupt 0000:01:00.0[A] -> GSI 16 (level, low) -> IRQ 16
[  560.915006] PCI: Setting latency timer of device 0000:01:00.0 to 64
[  560.920005] [fglrx] Power up the ASIC
[  560.923676] PM: Writing back config space on device 0000:10:00.0 at offset c (was 0, writing ace60000)
[  560.923686] PM: Writing back config space on device 0000:10:00.0 at offset 3 (was 0, writing 10)
[  560.923691] PM: Writing back config space on device 0000:10:00.0 at offset 1 (was 100000, writing 100006)
[  560.923707] PM: Writing back config space on device 0000:02:04.0 at offset f (was 18030100, writing 1803010b)
[  560.923718] PM: Writing back config space on device 0000:02:04.0 at offset 4 (was 0, writing c8400000)
[  560.923721] PM: Writing back config space on device 0000:02:04.0 at offset 3 (was 0, writing 4010)
[  560.923726] PM: Writing back config space on device 0000:02:04.0 at offset 1 (was 2900000, writing 2900002)
[  560.923739] PM: Writing back config space on device 0000:02:06.0 at offset f (was 34001ff, writing 5c0010b)
[  560.923743] PM: Writing back config space on device 0000:02:06.0 at offset e (was 0, writing 44fc)
[  560.923746] PM: Writing back config space on device 0000:02:06.0 at offset d (was 0, writing 4400)
[  560.923750] PM: Writing back config space on device 0000:02:06.0 at offset c (was 0, writing 40fc)
[  560.923753] PM: Writing back config space on device 0000:02:06.0 at offset b (was 0, writing 4000)
[  560.923757] PM: Writing back config space on device 0000:02:06.0 at offset a (was 0, writing 53fff000)
[  560.923761] PM: Writing back config space on device 0000:02:06.0 at offset 9 (was 0, writing 50000000)
[  560.923764] PM: Writing back config space on device 0000:02:06.0 at offset 8 (was 0, writing 543ff000)
[  560.923768] PM: Writing back config space on device 0000:02:06.0 at offset 7 (was 0, writing 54000000)
[  560.923772] PM: Writing back config space on device 0000:02:06.0 at offset 6 (was 0, writing b0060302)
[  560.923776] PM: Writing back config space on device 0000:02:06.0 at offset 4 (was 0, writing c8401000)
[  560.923782] PM: Writing back config space on device 0000:02:06.0 at offset 3 (was 820000, writing 82a810)
[  560.923790] PM: Writing back config space on device 0000:02:06.0 at offset 1 (was 2100000, writing 2100007)
[  562.509968] PM: Writing back config space on device 0000:02:06.2 at offset f (was 4020300, writing 402030a)
[  562.509968] PM: Writing back config space on device 0000:02:06.2 at offset 5 (was 0, writing c8404000)
[  562.509968] PM: Writing back config space on device 0000:02:06.2 at offset 4 (was 0, writing c8402000)
[  562.509968] PM: Writing back config space on device 0000:02:06.2 at offset 3 (was 800000, writing 804010)
[  562.509968] PM: Writing back config space on device 0000:02:06.2 at offset 1 (was 2100000, writing 2100006)
[  562.561109] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[22]  MMIO=[c8402000-c84027ff]  Max Packet=[2048]  IR/IT contexts=[4/8]
[  563.720201] PM: Writing back config space on device 0000:02:06.3 at offset f (was 40702ff, writing 407020a)
[  563.720232] PM: Writing back config space on device 0000:02:06.3 at offset 4 (was 0, writing c8408000)
[  563.720242] PM: Writing back config space on device 0000:02:06.3 at offset 3 (was 800000, writing 804010)
[  563.720254] PM: Writing back config space on device 0000:02:06.3 at offset 1 (was 2100000, writing 2100006)
[  563.720284] ACPI: PCI Interrupt 0000:02:06.3[B] -> GSI 19 (level, low) -> IRQ 19
[  563.755345] PM: Writing back config space on device 0000:02:06.4 at offset f (was 40703ff, writing 407030a)
[  563.755345] PM: Writing back config space on device 0000:02:06.4 at offset 6 (was 0, writing c840c000)
[  563.755345] PM: Writing back config space on device 0000:02:06.4 at offset 5 (was 0, writing c840b000)
[  563.755345] PM: Writing back config space on device 0000:02:06.4 at offset 4 (was 0, writing c840a000)
[  563.755345] PM: Writing back config space on device 0000:02:06.4 at offset 3 (was 800000, writing 804010)
[  563.755345] PM: Writing back config space on device 0000:02:06.4 at offset 1 (was 2100000, writing 2100006)
[  563.755345] ACPI: PCI Interrupt 0000:02:06.4[C] -> GSI 22 (level, low) -> IRQ 22
[  563.755345] PM: Writing back config space on device 0000:02:06.5 at offset f (was 1ff, writing 10b)
[  563.755345] PM: Writing back config space on device 0000:02:06.5 at offset 7 (was 0, writing c8410000)
[  563.755345] PM: Writing back config space on device 0000:02:06.5 at offset 6 (was 0, writing c840f000)
[  563.755345] PM: Writing back config space on device 0000:02:06.5 at offset 5 (was 0, writing c840e000)
[  563.755345] PM: Writing back config space on device 0000:02:06.5 at offset 4 (was 0, writing c840d000)
[  563.755345] PM: Writing back config space on device 0000:02:06.5 at offset 1 (was 2100000, writing 2100002)
[  563.763875] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[  564.094954] hda: UDMA/100 mode selected
[  564.101004] hdb: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[  564.101314] hdb: MWDMA2 mode selected
[  575.150713] Restarting all filesystems ...
[  575.152212] Restarting tasks ... done.
[  575.325883] ipw2200: Intel(R) PRO/Wireless 2200/2915 Network Driver, 1.2.2kmq
[  575.325891] ipw2200: Copyright(c) 2003-2006 Intel Corporation
[  575.326149] ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 21 (level, low) -> IRQ 21
[  575.326254] ipw2200: Detected Intel PRO/Wireless 2200BG Network Connection
[  575.326333] firmware: requesting ipw2200-bss.fw
[  575.490065] usb 3-2: USB disconnect, address 2
[  575.502811] ipw2200: Detected geography ZZR (14 802.11bg channels, 0 802.11a channels)
[  576.003848] ADDRCONF(NETDEV_UP): eth0: link is not ready
[  577.019797] usb 3-2: new full speed USB device using uhci_hcd and address 3
[  577.240640] usb 3-2: configuration #1 chosen from 1 choice
[  590.107867] eth2: no IPv6 routers present
[  735.485003] usb 3-1: new low speed USB device using uhci_hcd and address 4
[  735.663492] usb 3-1: configuration #1 chosen from 1 choice
[  735.715455] input: Logitech USB Receiver as /class/input/input6
[  735.722041] input: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:00:1d.1-1
[  735.755581] input: Logitech USB Receiver as /class/input/input7
[  735.762085] input,hiddev96: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:1d.1-1
[  916.554235] ACPI: PCI interrupt for device 0000:02:04.0 disabled
[  916.879142] PM: Syncing filesystems ... done.
[  917.717369] Stopping fuse filesystems.
[  917.725172] Fuse filesystem done.
[  917.732900] Freezing user space processes ... (elapsed 0.04 seconds) <6>done.
[  917.791438] Stopping normal filesystems.
[  917.804797] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[  917.814572] Suspending console(s)
[  918.768075] ACPI handle has no context!
[  918.768075] ACPI handle has no context!
[  918.768075] ACPI: PCI interrupt for device 0000:02:06.4 disabled
[  918.768075] ACPI handle has no context!
[  918.779126] ACPI handle has no context!
[  918.779146] ACPI: PCI interrupt for device 0000:02:06.3 disabled
[  918.779156] ACPI handle has no context!
[  918.790144] ACPI handle has no context!
[  918.801306] [fglrx] Power down the ASIC .
[  918.853045] ACPI: PCI interrupt for device 0000:01:00.0 disabled
[  918.853364] ACPI: PCI interrupt for device 0000:00:1e.3 disabled
[  918.864235] codec_write 0: semaphore is not ready for register 0x26
[  918.864341] codec_write 0: semaphore is not ready for register 0x26
[  918.864354] ACPI: PCI interrupt for device 0000:00:1e.2 disabled
[  918.864478] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
[  918.875118] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
[  918.875154] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
[  918.875190] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
[  918.892700] ACPI: Preparing to enter system sleep state S3
[  918.893011] Intel machine check architecture supported.
[  918.893029] Intel machine check reporting enabled on CPU#0.
[  918.893127] Back to C!
[  918.893699] Force enabled HPET at resume
[  919.086617] ACPI: EC: non-query interrupt received, switching to interrupt mode
[  927.155731] ACPI: EC: acpi_ec_wait timeout, status = 0x09, event = "b0=1"
[  927.155736] ACPI: EC: read timeout, command = 130
[  927.312838] PM: Writing back config space on device 0000:00:01.0 at offset f (was 100, writing c010a)
[  927.312838] PM: Writing back config space on device 0000:00:01.0 at offset 3 (was 10000, writing 10010)
[  927.312838] PCI: Setting latency timer of device 0000:00:01.0 to 64
[  927.312838] PM: Writing back config space on device 0000:00:1c.0 at offset f (was 100, writing 4010a)
[  927.312838] PM: Writing back config space on device 0000:00:1c.0 at offset 9 (was 10001, writing 1fff1)
[  927.312838] PM: Writing back config space on device 0000:00:1c.0 at offset 8 (was 0, writing c830c800)
[  927.312838] PM: Writing back config space on device 0000:00:1c.0 at offset 7 (was 0, writing f0)
[  927.312838] PM: Writing back config space on device 0000:00:1c.0 at offset 3 (was 810000, writing 810010)
[  927.312838] PM: Writing back config space on device 0000:00:1c.0 at offset 1 (was 100000, writing 100407)
[  927.312838] PCI: Setting latency timer of device 0000:00:1c.0 to 64
[  927.312838] PM: Writing back config space on device 0000:00:1c.1 at offset f (was 200, writing 4020b)
[  927.312838] PM: Writing back config space on device 0000:00:1c.1 at offset 9 (was 10001, writing 1fff1)
[  927.312838] PM: Writing back config space on device 0000:00:1c.1 at offset 8 (was 0, writing fff0)
[  927.312838] PM: Writing back config space on device 0000:00:1c.1 at offset 7 (was 20000000, writing f0)
[  927.312838] PM: Writing back config space on device 0000:00:1c.1 at offset 3 (was 810000, writing 810010)
[  927.312838] PM: Writing back config space on device 0000:00:1c.1 at offset 1 (was 100000, writing 100407)
[  927.312838] PCI: Setting latency timer of device 0000:00:1c.1 to 64
[  927.312838] uhci_hcd 0000:00:1d.0: enabling device (0000 -> 0001)
[  927.312838] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 20 (level, low) -> IRQ 20
[  927.312838] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[  927.312838] PM: Writing back config space on device 0000:00:1d.0 at offset f (was 100, writing 10a)
[  927.312838] PM: Writing back config space on device 0000:00:1d.0 at offset 8 (was 1, writing 3001)
[  927.312838] usb usb2: root hub lost power or was reset
[  927.312838] uhci_hcd 0000:00:1d.1: enabling device (0000 -> 0001)
[  927.312838] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 17 (level, low) -> IRQ 17
[  927.312838] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[  927.312838] PM: Writing back config space on device 0000:00:1d.1 at offset f (was 200, writing 20b)
[  927.312838] PM: Writing back config space on device 0000:00:1d.1 at offset 8 (was 1, writing 3021)
[  927.312838] usb usb3: root hub lost power or was reset
[  927.312838] uhci_hcd 0000:00:1d.2: enabling device (0000 -> 0001)
[  927.312838] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
[  927.312838] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[  927.312838] PM: Writing back config space on device 0000:00:1d.2 at offset f (was 300, writing 30b)
[  927.312838] PM: Writing back config space on device 0000:00:1d.2 at offset 8 (was 1, writing 3041)
[  927.312838] usb usb4: root hub lost power or was reset
[  927.325808] ehci_hcd 0000:00:1d.7: enabling device (0000 -> 0002)
[  927.325808] ACPI: PCI Interrupt 0000:00:1d.7[A] -> GSI 20 (level, low) -> IRQ 20
[  927.325808] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[  927.325808] PM: Writing back config space on device 0000:00:1d.7 at offset f (was 100, writing 10a)
[  927.325808] PM: Writing back config space on device 0000:00:1d.7 at offset 4 (was 0, writing c8c00000)
[  927.325808] PM: Writing back config space on device 0000:00:1e.0 at offset 9 (was 10001, writing 1fff1)
[  927.325808] PM: Writing back config space on device 0000:00:1e.0 at offset 8 (was 0, writing c870c840)
[  927.325808] PM: Writing back config space on device 0000:00:1e.0 at offset 7 (was 22800010, writing 22804040)
[  927.327012] PM: Writing back config space on device 0000:00:1e.0 at offset 1 (was 100001, writing 100107)
[  927.327036] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[  927.327056] PM: Writing back config space on device 0000:00:1e.2 at offset f (was 100, writing 10b)
[  927.327074] PM: Writing back config space on device 0000:00:1e.2 at offset 7 (was 0, writing c8c02000)
[  927.327083] PM: Writing back config space on device 0000:00:1e.2 at offset 6 (was 0, writing c8c01000)
[  927.327091] PM: Writing back config space on device 0000:00:1e.2 at offset 5 (was 1, writing 3201)
[  927.327100] PM: Writing back config space on device 0000:00:1e.2 at offset 4 (was 1, writing 3101)
[  927.327112] PM: Writing back config space on device 0000:00:1e.2 at offset 1 (was 2900000, writing 2900003)
[  927.327132] ACPI: PCI Interrupt 0000:00:1e.2[A] -> GSI 21 (level, low) -> IRQ 21
[  927.327142] PCI: Setting latency timer of device 0000:00:1e.2 to 64
[  928.575121] PM: Writing back config space on device 0000:00:1e.3 at offset f (was 200, writing 20a)
[  928.575121] PM: Writing back config space on device 0000:00:1e.3 at offset 5 (was 1, writing 3501)
[  928.575121] PM: Writing back config space on device 0000:00:1e.3 at offset 4 (was 1, writing 3401)
[  928.575121] PM: Writing back config space on device 0000:00:1e.3 at offset 1 (was 2900000, writing 2900001)
[  928.575121] ACPI: PCI Interrupt 0000:00:1e.3[B] -> GSI 22 (level, low) -> IRQ 22
[  928.575121] PCI: Setting latency timer of device 0000:00:1e.3 to 64
[  929.583005] PM: Writing back config space on device 0000:00:1f.1 at offset f (was 100, writing 10a)
[  929.583005] PM: Writing back config space on device 0000:00:1f.1 at offset 8 (was 1, writing 3581)
[  929.583005] PM: Writing back config space on device 0000:00:1f.1 at offset 1 (was 2800000, writing 2800005)
[  929.583005] ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 16 (level, low) -> IRQ 16
[  929.583623] ACPI: PCI Interrupt 0000:01:00.0[A] -> GSI 16 (level, low) -> IRQ 16
[  929.583634] PCI: Setting latency timer of device 0000:01:00.0 to 64
[  929.588776] [fglrx] Power up the ASIC
[  929.592482] PM: Writing back config space on device 0000:10:00.0 at offset c (was 0, writing ace60000)
[  929.592509] PM: Writing back config space on device 0000:10:00.0 at offset 3 (was 0, writing 10)
[  929.592522] PM: Writing back config space on device 0000:10:00.0 at offset 1 (was 100000, writing 100006)
[  929.592565] PM: Writing back config space on device 0000:02:04.0 at offset f (was 18030100, writing 1803010b)
[  929.592594] PM: Writing back config space on device 0000:02:04.0 at offset 4 (was 0, writing c8400000)
[  929.592604] PM: Writing back config space on device 0000:02:04.0 at offset 3 (was 0, writing 4010)
[  929.592615] PM: Writing back config space on device 0000:02:04.0 at offset 1 (was 2900000, writing 2900002)
[  929.592651] PM: Writing back config space on device 0000:02:06.0 at offset f (was 34001ff, writing 5c0010b)
[  929.592660] PM: Writing back config space on device 0000:02:06.0 at offset e (was 0, writing 44fc)
[  929.592670] PM: Writing back config space on device 0000:02:06.0 at offset d (was 0, writing 4400)
[  929.592679] PM: Writing back config space on device 0000:02:06.0 at offset c (was 0, writing 40fc)
[  929.592689] PM: Writing back config space on device 0000:02:06.0 at offset b (was 0, writing 4000)
[  929.592699] PM: Writing back config space on device 0000:02:06.0 at offset a (was 0, writing 53fff000)
[  929.592708] PM: Writing back config space on device 0000:02:06.0 at offset 9 (was 0, writing 50000000)
[  929.592718] PM: Writing back config space on device 0000:02:06.0 at offset 8 (was 0, writing 543ff000)
[  929.592728] PM: Writing back config space on device 0000:02:06.0 at offset 7 (was 0, writing 54000000)
[  929.592737] PM: Writing back config space on device 0000:02:06.0 at offset 6 (was 0, writing b0060302)
[  929.592749] PM: Writing back config space on device 0000:02:06.0 at offset 4 (was 0, writing c8401000)
[  929.592759] PM: Writing back config space on device 0000:02:06.0 at offset 3 (was 820000, writing 82a810)
[  929.592771] PM: Writing back config space on device 0000:02:06.0 at offset 1 (was 2100000, writing 2100007)
[  929.872778] PM: Writing back config space on device 0000:02:06.2 at offset f (was 4020300, writing 402030a)
[  929.872778] PM: Writing back config space on device 0000:02:06.2 at offset 5 (was 0, writing c8404000)
[  929.872778] PM: Writing back config space on device 0000:02:06.2 at offset 4 (was 0, writing c8402000)
[  929.872778] PM: Writing back config space on device 0000:02:06.2 at offset 3 (was 800000, writing 804010)
[  929.872778] PM: Writing back config space on device 0000:02:06.2 at offset 1 (was 2100000, writing 2100006)
[  929.922990] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[22]  MMIO=[c8402000-c84027ff]  Max Packet=[2048]  IR/IT contexts=[4/8]
[  930.002995] PM: Writing back config space on device 0000:02:06.3 at offset f (was 40702ff, writing 407020a)
[  930.002995] PM: Writing back config space on device 0000:02:06.3 at offset 4 (was 0, writing c8408000)
[  930.002995] PM: Writing back config space on device 0000:02:06.3 at offset 3 (was 800000, writing 804010)
[  930.002995] PM: Writing back config space on device 0000:02:06.3 at offset 1 (was 2100000, writing 2100006)
[  930.002995] ACPI: PCI Interrupt 0000:02:06.3[B] -> GSI 19 (level, low) -> IRQ 19
[  930.033028] PM: Writing back config space on device 0000:02:06.4 at offset f (was 40703ff, writing 407030a)
[  930.033028] PM: Writing back config space on device 0000:02:06.4 at offset 6 (was 0, writing c840c000)
[  930.033028] PM: Writing back config space on device 0000:02:06.4 at offset 5 (was 0, writing c840b000)
[  930.033028] PM: Writing back config space on device 0000:02:06.4 at offset 4 (was 0, writing c840a000)
[  930.033028] PM: Writing back config space on device 0000:02:06.4 at offset 3 (was 800000, writing 804010)
[  930.033028] PM: Writing back config space on device 0000:02:06.4 at offset 1 (was 2100000, writing 2100006)
[  930.033028] ACPI: PCI Interrupt 0000:02:06.4[C] -> GSI 22 (level, low) -> IRQ 22
[  930.033028] PM: Writing back config space on device 0000:02:06.5 at offset f (was 1ff, writing 10b)
[  930.033028] PM: Writing back config space on device 0000:02:06.5 at offset 7 (was 0, writing c8410000)
[  930.033028] PM: Writing back config space on device 0000:02:06.5 at offset 6 (was 0, writing c840f000)
[  930.033028] PM: Writing back config space on device 0000:02:06.5 at offset 5 (was 0, writing c840e000)
[  930.033028] PM: Writing back config space on device 0000:02:06.5 at offset 4 (was 0, writing c840d000)
[  930.033028] PM: Writing back config space on device 0000:02:06.5 at offset 1 (was 2100000, writing 2100002)
[  930.043039] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[  930.386506] hda: UDMA/100 mode selected
[  930.405004] hdb: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[  930.405004] hdb: MWDMA2 mode selected
[  942.687301] usb 3-1: reset low speed USB device using uhci_hcd and address 4
[  944.311573] Restarting all filesystems ...
[  944.322556] Restarting tasks ... done.
[  944.481132] ipw2200: Intel(R) PRO/Wireless 2200/2915 Network Driver, 1.2.2kmq
[  944.481140] ipw2200: Copyright(c) 2003-2006 Intel Corporation
[  944.481337] ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 21 (level, low) -> IRQ 21
[  944.485946] ipw2200: Detected Intel PRO/Wireless 2200BG Network Connection
[  944.486026] firmware: requesting ipw2200-bss.fw
[  944.794738] ipw2200: Detected geography ZZR (14 802.11bg channels, 0 802.11a channels)
[  944.817532] usb 3-2: USB disconnect, address 3
[  945.026290] ADDRCONF(NETDEV_UP): eth0: link is not ready
[  946.116276] usb 3-2: new full speed USB device using uhci_hcd and address 5
[  946.198038] hub 3-0:1.0: unable to enumerate USB device on port 2
[  950.714927] ADDRCONF(NETDEV_UP): eth2: link is not ready
[  951.824642] ipw2200: Failed to send RSN_CAPABILITIES: Command timed out.
[  953.385185] ipw2200: Failed to send SSID: Command timed out.
Comment 1 Hannes Diethelm 2008-09-03 11:36:03 UTC
The dmesg output is after two suspend/resume cycles. I tryed nearly every kernel version and every hack...

Also after two or three suspend-resume cycles the system is acting realy slow, and    while shuting down, I need to press keys or nothing happens while shuting down all processes.
Comment 2 ykzhao 2008-09-08 00:02:06 UTC
Created attachment 17675 [details]
Patch 1/4 : Don't issue the burst disable command if EC exits the burst mode
Comment 3 ykzhao 2008-09-08 00:17:39 UTC
Created attachment 17676 [details]
Patch 2/4: Clear the query_pending bit only after processing EC notification event
Comment 4 ykzhao 2008-09-08 00:19:42 UTC
Created attachment 17677 [details]
Patch 3/4: Simplify EC working flowchart and always enable EC GPE
Comment 5 ykzhao 2008-09-08 00:21:17 UTC
Created attachment 17678 [details]
patch 4/4: Add some udelay in EC GPE handler to avoid EC GPE interrupt storm
Comment 6 ykzhao 2008-09-08 00:35:47 UTC
Hi, Hannes
    From the dmesg it seems that there exists the following info in course of resuming.
    >[  919.086617] ACPI: EC: non-query interrupt received, switching to interrupt
mode
   > [  927.155731] ACPI: EC: acpi_ec_wait timeout, status = 0x09, event = "b0=1"
   > [  927.155736] ACPI: EC: read timeout, command = 130
    Maybe it is related with EC driver.
    Will you please try the attached four patches on the latest kernel(2.6.27-rc5) and see whether the resume is still very slow?
    After the test, please attach the output of dmesg, acpidump.
    Thanks. 
Comment 7 Hannes Diethelm 2008-09-14 13:08:05 UTC
Created attachment 17777 [details]
dmesg output of patched kernel after one suspend/resume
Comment 8 Hannes Diethelm 2008-09-14 13:09:01 UTC
Created attachment 17778 [details]
acpidump of patched kernel before suspend/resume
Comment 9 Hannes Diethelm 2008-09-14 13:12:48 UTC
Thanks! It's working great now! :D

Suspend/resume takes now abaut 1-2 seconds.

When will this patch be in the stable kernel?

If you need other help, no problem.
Comment 10 Hannes Diethelm 2008-09-14 14:54:01 UTC
Created attachment 17779 [details]
Quick and dirty backport to 2.6.26 of these four patches
Comment 11 Hannes Diethelm 2008-09-14 14:57:05 UTC
I made a backport to 2.6.26 of these four patches beacuse I got some other problemes with the rc-kernel. May be someone can use it.
Comment 12 ykzhao 2008-09-15 19:14:39 UTC
Hi, Hannes
    Thanks for the test. And it is very lucky that your system can work well after applying the patches.Now another people also works on this issue and my patches are not accepted by him. Of course I am discussing this issue with him.
    If reaching an agreement about this issue, will you please try the updated patch on your laptop?
    thanks.
    
Comment 13 ykzhao 2008-09-16 18:44:11 UTC
Created attachment 17824 [details]
Patch 3/4: Switch to polling mode when there is no EC GPE interrupt for some EC transactions
Comment 14 ykzhao 2008-09-16 18:45:33 UTC
Created attachment 17825 [details]
patch 4/4: Add some delay in EC GPE handler to avoid EC GPE storm
Comment 15 ykzhao 2008-09-16 18:48:31 UTC
Hi, Hannes
    Will you please try the updated patches again and see whether the resume is still normal? 
    Of course the patches in comment #2,#3 is still required.
    thanks again.
Comment 16 Hannes Diethelm 2008-09-17 06:23:12 UTC
Created attachment 17836 [details]
dmesg output of patched kernel after one suspend/resume new patchset / 2.6.27-rc6
Comment 17 Hannes Diethelm 2008-09-17 06:26:14 UTC
Hi Ykzhao

I tryed the new patches but they are not working for me. The symptoms are the same. Slow resume and also after resume some keystrokes are lost and some processes are realy slow and can be speed up by typing on the keyboard.
Comment 18 ykzhao 2008-09-17 07:59:35 UTC
Hi, Hannes
    Will you please try the patches on the 2.6.27-rc5 kernel and see whether the resume is still slow?
    Will you please double check the test result in comment #9?
    thanks.
Comment 19 Hannes Diethelm 2008-09-19 01:54:53 UTC
Hi!

I made some tests... It's strange that also patchset 1 is not working with a different configuration then mine. May be i also forgot to mention how my laptop behaves on resuning: If i press the start button then the hardware starts and the harddrive light lights up. After abaut 30s it goes off. After this, nothing more happens and the screen stays black. Also the caps-lock is not reacting at all.

2.6.26-1-686			no patch	not resuming at all / hdlight / no capslock		default debian kernel

2.6.26-laptop			patchset 1	not resuming at all / hdlight / no capslock		default debian kernel with small changes

2.6.26.3-bootsplash		patchset 1	fast, 1-2s 						my own kernel

2.6.27-rc5-debconf-1 		patchset 1	not resuming at all / hdlight / no capslock		debian config / all new default

2.6.27-rc5-debconf-1-1000hz 	patchset 1	not resuming at all / hdlight / no capslock		debian config / all new default / 1000hz

2.6.27-rc5-test1 		patchset 1	fast , 1-2s						my own kernel config / all new default

2.6.27-rc5-test2		patchset 2	resuing slow or not at all / hdlight / no capslock	my own kernel config / all new default 

Is there some posibility to debug this problem myself? I was thinking abaut kgdb but it's  not working for me because i dont's have a rs232. I'm quite experienced in c programming, but i don't have the time to get into the kernel acpi code and it's also a bit over my level. But if i can help you to find where the kernel hangs while resuming, i'll do. But I need some instructions how to do so.
Comment 20 Hannes Diethelm 2008-09-19 01:56:47 UTC
Created attachment 17878 [details]
All configs and dmesg output of tested kernels
Comment 21 ykzhao 2008-09-20 07:49:38 UTC
Hi, Hannes
    Does the patch set 1 mean that the patch in comment #2,3,4,5 is used?
    I have no idea why the test result is different after the patch set 2 is used on the 2.6.27-rc5 kernel. 
    From the test log we can know that the following message doesn't appear again regardless the patch set 1 or 2 is applied.
     > ACPI: EC: non-query interrupt received, switching to interrupt mode
     > ACPI: EC: acpi_ec_wait timeout, status = 0x09, event ="b0=1"
     > ACPI: EC: read timeout, command = 130

    But from the log in 2.6.27-rc5-test2 we can see the following message which looks so confusing.
     >[  131.043453] Back to C!
     >   300.068785] Force enabled HPET at resume
     > [  131.045091] ACPI: Waking up from system sleep state S3
     > [  158.182237] pcieport-driver 0000:00:01.0: restoring config space at offset 0xf (was 0x100, writing 0xc010a)
     
    Will you please try the boot option of "hpet=disable idle=poll" when the patch set 2 is used?
    thanks.

    
Comment 22 Hannes Diethelm 2008-09-23 04:41:29 UTC
Hi!
Yes, patchset 1 means patches from comments 2 to 5 and patchset 2 the patches from comments 2,3,13,14.

I've tested the boot option "hpet=disable idle=poll" and now also patchset 2 is working!
Comment 23 Hannes Diethelm 2008-09-23 04:43:27 UTC
Created attachment 17970 [details]
2.6.27-rc5 patchset 2 cmdline: hpet=disable idle=poll
Comment 24 ykzhao 2008-09-23 17:55:38 UTC
Hi, Hannes
    Thanks for the test. Now it seems clear that the issue is not related with the EC driver. Maybe it is caused by the hpet or C-state. But we had better confirm the root cause.
   Will you please try the following boot options and see whether the problem still exists? Of course the patch set 2 is still used.(The boot option should be used independently.)
   a. idle=poll
   b. processor.max_cstate=1
   c. hpet=disable
   thanks.
   
   
   
Comment 25 Hannes Diethelm 2008-09-24 07:55:49 UTC
Ok, i've tested this boot options:
idle=poll               working
processor.max_cstate=1  working
hpet=disable            not working
Comment 26 ykzhao 2008-09-25 22:09:01 UTC
Hi,Hannes
   Thanks for the test.
   Now it is confirmed that this issue is related with C-states.
   Will you please do the following test?(Please don't add any boot option mentioned in comment #24).
    a. suspend to RAM
    b. press the power button to wake up the system. wait for about 20 seconds and press the power button again.
    c. after the system returns, please attach the output of dmesg. 
   thanks.
    
Comment 27 Hannes Diethelm 2008-09-25 23:27:31 UTC
Hi!

Ok, I did this. After 20s I pressed the power button. But nothing happened. So after quite some time, i started to press random keys on the keyboard ( but only the keyboard ) so the system resumed.

Whats the meaning of pressing the power button again?

(All tests with the new patchset and 2.6.27-rc5)
Comment 28 Hannes Diethelm 2008-09-25 23:29:14 UTC
Created attachment 18053 [details]
Dmesg output of test described in comment #26
Comment 29 ykzhao 2008-09-26 00:49:37 UTC
Hi, Hannes
    Sorry that I don't describe it very clearly.
    What I expected is that you wait for 20 seconds and press the power button again(of course pressing keyboard is also OK) after pressing the power button to resume the system. 
    Will you please do it again?
    Thanks again.
     
Comment 30 Hannes Diethelm 2008-09-26 06:29:13 UTC
Hmm.... I don't know what you mean.
In the previous test i did this:
1. Suspend to ram
2. press power button to resume
3. wait 20s
4. press power button again
5. wait some time (~1-2min)
6. press a lot of keys (~100-200 keystrokes)
7. system is up

If you d'like I could press a key every 2 secons so you can see where the system is hanging.
Comment 31 ykzhao 2008-09-26 20:53:50 UTC
Hi, Hannes
    What you have done is very right.
    From the log in comment #28 it seems that it will take so long time to resume. So I think that maybe you misunderstand what I have said. In fact what you have done is correct. 
   thanks.
Comment 32 Hannes Diethelm 2008-10-15 23:16:49 UTC
Is there something going on abaut this bug?
Comment 33 ykzhao 2008-10-15 23:33:53 UTC
Hi, Hannes
    Now the updated EC patch is available. Will you please try the patch in http://bugzilla.kernel.org/show_bug.cgi?id=10724#C142 and see whether the problem still exists?
    If the problem still exists, please add the following boot options and try it again. 
    a. idle=poll
    b. nolapic_itmer
    c. processor.max_cstate=1


    Thanks.
Comment 34 Hannes Diethelm 2008-10-16 05:04:27 UTC
Hi!

I tested the patch from http://bugzilla.kernel.org/show_bug.cgi?id=10724#C142 and it seams to work!

Thanks!
Comment 35 Hannes Diethelm 2008-10-20 02:31:34 UTC
Created attachment 18373 [details]
Dmesg output 2.6.27-rc7 with patch from http://bugzilla.kernel.org/show_bug.cgi?id=10724#C142
Comment 36 ykzhao 2008-10-24 00:45:09 UTC
thanks for the test.
As the system can work well after applying the patch, the bug will be marked as resovled.
    
Thanks.
Comment 37 Len Brown 2008-10-27 19:23:40 UTC
patch above shipped in linux-2.6.28-rc1
closed

Note You need to log in before you can comment on or make changes to this bug.