Bug 14130

Summary: system hangs while resuming to disk
Product: Power Management Reporter: Andrzej (adk_rzeszow)
Component: Hibernation/SuspendAssignee: power-management_other
Status: REJECTED INSUFFICIENT_DATA    
Severity: blocking CC: ikalvachev, lenb, rjw, rui.zhang, yakui.zhao
Priority: P1    
Hardware: All   
OS: Linux   
Kernel Version: 2.6.27, 2.6.30.5 Subsystem:
Regression: Yes Bisected commit-id:
Bug Depends on:    
Bug Blocks: 7216    
Attachments: lspci and acpidump output

Description Andrzej 2009-09-06 10:21:39 UTC
On my desktop PC I am unable to use hibernation with new kernel versions. pm-suspend utility hibernates my system correctly (using swap partition). When resuming, everything seems to be working, console shows:

[ 4.549609] Freezing user space processes ... (elapsed 0.00 secodns) done.
[ 4.558416] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[ 4.562098] PM: Loading image data pages (72422 pages) ... dome
[ 14.141787] PM: Read 289768 kbytes in 9.57 seconds (30.27 MB/s)
[ 14.141863] Suspending console(s) (use no_console_suspend to debug)

After that, system hangs. It does not react on keyboard (including magic SysRq) and does not resume X11.

OpenSuSE 11.1 with kernel 2.6.27.21-0.1-pae -> it resumes correctly 1 of 10 times.
Debian Lenny, kernel 2.6.30.5 (self-compiled) -> always hangs.

Old kernels (2.6.18 and 2.6.20) are working correctly (no hangs), but suspending is much slower.

My PC's lshw:
==========================
linux
    description: Desktop Computer
    product: P4S55FX2
    vendor: Uknown Chassis Manufacture
    version: 1.0
    serial: 00000000
    width: 32 bits
    capabilities: smbios-2.3 dmi-2.3
    configuration: chassis=desktop
  *-core
       description: Motherboard
       product: P4S55FX2
       physical id: 0
       version: 1.0
       serial: 00000000
     *-firmware
          description: BIOS
          vendor: American Megatrends Inc.
          physical id: 0
          version: P1.30 (05/26/2004)
          size: 64KiB
          capacity: 192KiB
          capabilities: isa pci pnp apm upgrade shadowing escd cdboot bootselect socketedrom edd int13floppy360 int13floppy1200 int13floppy720 int13floppy2880 int5printscreen int9keyboard int17printer int10video acpi usb agp ls120boot zipboot biosbootspecification
     *-cpu
          description: CPU
          product: Intel(R) Celeron(R) CPU 2.00GHz
          vendor: Intel Corp.
          physical id: 4
          bus info: cpu@0
          version: 15.2.9
          slot: FC-478
          size: 2GHz
          capacity: 3GHz
          width: 32 bits
          clock: 100MHz
          capabilities: fpu fpu_exception wp vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe up pebs bts cid xtpr
          configuration: id=0
        *-cache:0
             description: L1 cache
             physical id: 5
             slot: Internal Cache
             size: 8KiB
             capacity: 1MiB
             clock: 25MHz (40.0ns)
             capabilities: pipeline-burst synchronous internal write-back data
        *-cache:1
             description: L2 cache
             physical id: 6
             slot: Internal Cache
             size: 128KiB
             capacity: 1MiB
             clock: 25MHz (40.0ns)
             capabilities: synchronous internal write-back unified
     *-memory
          description: System memory
          physical id: 1
          size: 1010MiB
     *-pci
          description: Host bridge
          product: 655 Host
          vendor: Silicon Integrated Systems [SiS]
          physical id: 100
          bus info: pci@0000:00:00.0
          version: 50
          width: 32 bits
          clock: 33MHz
          configuration: driver=agpgart-sis latency=32 module=sis_agp
        *-pci
             description: PCI bridge
             product: SiS AGP Port (virtual PCI-to-PCI bridge)
             vendor: Silicon Integrated Systems [SiS]
             physical id: 1
             bus info: pci@0000:00:01.0
             version: 00
             width: 32 bits
             clock: 66MHz
             capabilities: pci normal_decode bus_master
           *-display
                description: VGA compatible controller
                product: NV28 [GeForce4 Ti 4200 AGP 8x]
                vendor: nVidia Corporation
                physical id: 0
                bus info: pci@0000:01:00.0
                version: a1
                width: 32 bits
                clock: 66MHz
                capabilities: pm agp agp-3.0 vga_controller bus_master cap_list
                configuration: driver=nvidia latency=248 maxlatency=1 mingnt=5 module=nvidia
        *-isa
             description: ISA bridge
             product: SiS964 [MuTIOL Media IO]
             vendor: Silicon Integrated Systems [SiS]
             physical id: 2
             bus info: pci@0000:00:02.0
             version: 36
             width: 32 bits
             clock: 33MHz
             capabilities: isa bus_master
             configuration: latency=0
        *-ide
             description: IDE interface
             product: 5513 [IDE]
             vendor: Silicon Integrated Systems [SiS]
             physical id: 2.5
             bus info: pci@0000:00:02.5
             logical name: scsi0
             logical name: scsi1
             version: 01
             width: 32 bits
             clock: 33MHz
             capabilities: ide pm bus_master cap_list emulated
             configuration: driver=pata_sis latency=128 module=pata_sis
           *-disk:0
                description: ATA Disk
                product: IBM-DTLA-305030
                vendor: IBM
                physical id: 0
                bus info: scsi@0:0.0.0
                logical name: /dev/sda
                version: TW3O
                serial: YGE********
                size: 28GiB (30GB)
                capabilities: partitioned partitioned:dos
                configuration: ansiversion=5 signature=287a2879
              *-volume:0
                   description: Windows FAT volume
                   vendor: MSWIN4.1
                   physical id: 1
                   bus info: scsi@0:0.0.0,1
                   logical name: /dev/sda1
                   logical name: /mnt/win
                   version: FAT32
                   serial: 4830-****
                   size: 10001MiB
                   capacity: 10001MiB
                   capabilities: primary bootable fat initialized
                   configuration: FATs=2 filesystem=fat label=WIN mount.fstype=vfat mount.options=rw,relatime,uid=1000,gid=100,fmask=0022,dmask=0000,allow_utime=0022,codepage=cp852,iocharset=utf8 state=mounted
              *-volume:1
                   description: Extended partition
                   physical id: 2
                   bus info: scsi@0:0.0.0,2
                   logical name: /dev/sda2
                   size: 18GiB
                   capacity: 18GiB
                   capabilities: primary extended partitioned partitioned:extended
                 *-logicalvolume
                      description: Linux filesystem partition
                      physical id: 5
                      logical name: /dev/sda5
                      logical name: /mnt/linux
                      capacity: 18GiB
                      configuration: mount.fstype=ext3 mount.options=rw,relatime,errors=continue,data=ordered state=mounted
           *-disk:1
                description: ATA Disk
                product: SAMSUNG SP1203N
                physical id: 0.1.0
                bus info: scsi@0:0.1.0
                logical name: /dev/sdb
                version: TL10
                serial: 1190**********
                size: 111GiB (120GB)
                capabilities: partitioned partitioned:dos
                configuration: ansiversion=5 signature=427d0a80
              *-volume:0
                   description: Linux filesystem partition
                   physical id: 1
                   bus info: scsi@0:0.1.0,1
                   logical name: /dev/sdb1
                   version: 3.6
                   serial: 05877******
                   size: 47MiB
                   capacity: 47MiB
                   capabilities: primary journaled reiserfs initialized
                   configuration: filesystem=reiserfs hash=r5 state=clean
              *-volume:1
                   description: EXT3 volume
                   vendor: Linux
                   physical id: 2
                   bus info: scsi@0:0.1.0,2
                   logical name: /dev/sdb2
                   logical name: /
                   version: 1.0
                   serial: f7ede8ee-***
                   size: 38GiB
                   capacity: 38GiB
                   capabilities: primary journaled extended_attributes large_files huge_files recover ext3 ext2 initialized
                   configuration: created=2008-11-24 12:56:10 filesystem=ext3 modified=2009-09-06 11:58:27 mount.fstype=ext3 mount.options=rw,relatime,errors=continue,data=ordered mounted=2009-09-06 11:58:27 state=mounted
              *-volume:2
                   description: Linux swap volume
                   physical id: 3
                   bus info: scsi@0:0.1.0,3
                   logical name: /dev/sdb3
                   version: 1
                   serial: eea98649-***
                   size: 1025MiB
                   capacity: 1025MiB
                   capabilities: primary nofs swap initialized
                   configuration: filesystem=swap pagesize=4096
              *-volume:3
                   description: Windows NTFS volume
                   physical id: 4
                   bus info: scsi@0:0.1.0,4
                   logical name: /dev/sdb4
                   logical name: /mnt/winda
                   version: 3.1
                   serial: 4e35c4db-***
                   size: 71GiB
                   capacity: 71GiB
                   capabilities: primary bootable ntfs initialized
                   configuration: clustersize=4096 created=2008-11-09 16:16:33 filesystem=ntfs label=WINDA mount.fstype=fuseblk mount.options=rw,relatime,user_id=0,group_id=0,default_permissions,allow_other,blksize=4096 state=mounted
           *-cdrom
                description: DVD writer
                product: DVD-RW  DVR-115D
                vendor: PIONEER
                physical id: 1
                bus info: scsi@1:0.0.0
                logical name: /dev/cdrom
                logical name: /dev/cdrw
                logical name: /dev/dvd
                logical name: /dev/dvdrw
                logical name: /dev/scd0
                logical name: /dev/sr0
                version: 1.18
                capabilities: removable audio cd-r cd-rw dvd dvd-r
                configuration: ansiversion=5 status=nodisc
        *-multimedia
             description: Multimedia audio controller
             product: AC'97 Sound Controller
             vendor: Silicon Integrated Systems [SiS]
             physical id: 2.7
             bus info: pci@0000:00:02.7
             version: a0
             width: 32 bits
             clock: 33MHz
             capabilities: pm bus_master cap_list
             configuration: driver=Intel ICH latency=32 maxlatency=11 mingnt=52 module=snd_intel8x0
        *-usb:0
             description: USB Controller
             product: USB 1.1 Controller
             vendor: Silicon Integrated Systems [SiS]
             physical id: 3
             bus info: pci@0000:00:03.0
             version: 0f
             width: 32 bits
             clock: 33MHz
             capabilities: ohci bus_master
             configuration: driver=ohci_hcd latency=32 maxlatency=80 module=ohci_hcd
           *-usbhost
                product: OHCI Host Controller
                vendor: Linux 2.6.30.5-adk ohci_hcd
                physical id: 1
                bus info: usb@1
                logical name: usb1
                version: 2.06
                capabilities: usb-1.10
                configuration: driver=hub slots=3 speed=12.0MB/s
        *-usb:1
             description: USB Controller
             product: USB 1.1 Controller
             vendor: Silicon Integrated Systems [SiS]
             physical id: 3.1
             bus info: pci@0000:00:03.1
             version: 0f
             width: 32 bits
             clock: 33MHz
             capabilities: ohci bus_master
             configuration: driver=ohci_hcd latency=32 maxlatency=80 module=ohci_hcd
           *-usbhost
                product: OHCI Host Controller
                vendor: Linux 2.6.30.5-adk ohci_hcd
                physical id: 1
                bus info: usb@3
                logical name: usb3
                version: 2.06
                capabilities: usb-1.10
                configuration: driver=hub slots=3 speed=12.0MB/s
              *-usb
                   description: Human interface device
                   product: 5-Axis,12-Button with POV
                   physical id: 2
                   bus info: usb@3:2
                   version: 1.00
                   capabilities: usb-1.10
                   configuration: driver=usbhid maxpower=300mA speed=1.5MB/s
        *-usb:2
             description: USB Controller
             product: USB 1.1 Controller
             vendor: Silicon Integrated Systems [SiS]
             physical id: 3.2
             bus info: pci@0000:00:03.2
             version: 0f
             width: 32 bits
             clock: 33MHz
             capabilities: ohci bus_master
             configuration: driver=ohci_hcd latency=32 maxlatency=80 module=ohci_hcd
           *-usbhost
                product: OHCI Host Controller
                vendor: Linux 2.6.30.5-adk ohci_hcd
                physical id: 1
                bus info: usb@4
                logical name: usb4
                version: 2.06
                capabilities: usb-1.10
                configuration: driver=hub slots=2 speed=12.0MB/s
              *-usb
                   description: Keyboard
                   product: USB Receiver
                   vendor: Logitech
                   physical id: 1
                   bus info: usb@4:1
                   version: 30.07
                   capabilities: usb-1.10
                   configuration: driver=usbhid maxpower=98mA speed=1.5MB/s
        *-usb:3
             description: USB Controller
             product: USB 2.0 Controller
             vendor: Silicon Integrated Systems [SiS]
             physical id: 3.3
             bus info: pci@0000:00:03.3
             version: 00
             width: 32 bits
             clock: 33MHz
             capabilities: pm ehci bus_master cap_list
             configuration: driver=ehci_hcd latency=32 maxlatency=80 module=ehci_hcd
           *-usbhost
                product: EHCI Host Controller
                vendor: Linux 2.6.30.5-adk ehci_hcd
                physical id: 1
                bus info: usb@2
                logical name: usb2
                version: 2.06
                capabilities: usb-2.00
                configuration: driver=hub slots=8 speed=480.0MB/s
              *-usb
                   description: Generic USB device
                   product: 802.11 bg WLAN
                   vendor: Ralink
                   physical id: 6
                   bus info: usb@2:6
                   version: 0.01
                   capabilities: usb-2.00
                   configuration: driver=rt73usb maxpower=300mA speed=480.0MB/s
        *-network DISABLED
             description: Ethernet interface
             product: SiS900 PCI Fast Ethernet
             vendor: Silicon Integrated Systems [SiS]
             physical id: 4
             bus info: pci@0000:00:04.0
             logical name: eth0
             version: 90
             serial: 00:0b:****
             size: 10MB/s
             capacity: 100MB/s
             width: 32 bits
             clock: 33MHz
             capabilities: pm bus_master cap_list ethernet physical tp mii 10bt 10bt-fd 100bt 100bt-fd autonegotiation
             configuration: autonegotiation=on broadcast=yes driver=sis900 driverversion=v1.08.10 Apr. 2 2006 duplex=half latency=32 link=no maxlatency=11 mingnt=52 module=sis900 multicast=yes port=MII speed=10MB/s
  *-network
       description: Wireless interface
       physical id: 1
       logical name: wlan0
       serial: 00:0e:*****
       capabilities: ethernet physical wireless
       configuration: broadcast=yes ip=192.168.*** multicast=yes wireless=IEEE 802.11bg
Comment 1 Rafael J. Wysocki 2009-09-06 12:07:15 UTC
I guess 2.6.31-rc9 also hangs?
Comment 2 ykzhao 2009-09-07 02:13:05 UTC
hi, Andrzej
    Will you please double check whether it hangs in course of hibernation/resume?
    
    Will you please check whether the suspend/resume can work on your box? If it can work, please attach the output of dmesg after suspend/resume.
    Thanks.
Comment 3 Andrzej 2009-09-07 08:47:35 UTC
> I guess 2.6.31-rc9 also hangs?
Yes, it also hangs.
> Will you please double check whether it hangs in course of
hibernation/resume?

I tried to start my system with minimal set of processes and modules and it also hangs, so I guess it's a kernel issue.

> Will you please check whether the suspend/resume can work on your box? If
it can work, please attach the output of dmesg after suspend/resume.

Suspending to RAM works. Dmesg output:
[  128.606343] PM: Syncing filesystems ... done.
[  128.610952] Freezing user space processes ... (elapsed 0.00 seconds) done.
[  128.611904] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[  128.612193] Suspending console(s) (use no_console_suspend to debug)
[  128.652032] sd 0:0:1:0: [sdb] Synchronizing SCSI cache
[  128.739613] sd 0:0:1:0: [sdb] Stopping disk
[  128.956248] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  128.956490] sd 0:0:0:0: [sda] Stopping disk
[  131.016337] ns558 00:0b: disabled
[  131.016511] parport_pc 00:0a: disabled
[  131.016729] serial 00:09: disabled
[  131.016803] ACPI handle has no context!
[  131.071603] ehci_hcd 0000:00:03.3: PCI INT D disabled
[  131.071651] ehci_hcd 0000:00:03.3: PME# disabled
[  131.084035] ohci_hcd 0000:00:03.2: PCI INT C disabled
[  131.084073] ohci_hcd 0000:00:03.1: PCI INT B disabled
[  131.084108] ohci_hcd 0000:00:03.0: PCI INT A disabled
[  131.084337] Intel ICH 0000:00:02.7: PCI INT C disabled
[  131.100714] ACPI: Preparing to enter system sleep state S3
[  131.101039] Disabling non-boot CPUs ...
[  131.104004] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[  131.104004] Intel machine check architecture supported.
[  131.104004] Intel machine check reporting enabled on CPU#0.
[  131.104004] CPU0: Intel P4/Xeon Extended MCE MSRs (12) available
[  131.104004] CPU0: Thermal LVT vector (0xfa) already installed
[  131.104004] Back to C!
[  131.104004] ACPI: Waking up from system sleep state S3
[  131.116050] pata_sis 0000:00:02.5: restoring config space at offset 0x1 (was 0x2100001, writing 0x2100005)
[  131.148621] Intel ICH 0000:00:02.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[  131.158046] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
[  131.180057] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
[  131.204052] ohci_hcd 0000:00:03.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
[  131.228058] ehci_hcd 0000:00:03.3: PME# disabled
[  131.228066] ehci_hcd 0000:00:03.3: PCI INT D -> GSI 23 (level, low) -> IRQ 23
[  131.228078] ehci_hcd 0000:00:03.3: PME# disabled
[  131.312542] ata1.01: ACPI cmd ef/03:0c:00:00:00:b0 filtered out
[  131.312549] ata1.01: ACPI cmd ef/03:45:00:00:00:b0 filtered out
[  131.312660] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out
[  131.312666] ata2.00: ACPI cmd ef/03:44:00:00:00:a0 filtered out
[  131.443305] serial 00:09: activated
[  131.444733] parport_pc 00:0a: activated
[  131.445566] ns558 00:0b: activated
[  131.448349] ata2.00: configured for UDMA/66
[  131.576035] sd 0:0:0:0: [sda] Starting disk
[  136.320290] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out
[  136.320297] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out
[  136.382120] ata1.00: configured for UDMA/100
[  136.388417] ata1.01: configured for UDMA/100
[  136.423747] sd 0:0:1:0: [sdb] Starting disk
[  142.004838] Restarting tasks ... done.
Comment 4 Len Brown 2009-09-09 02:15:19 UTC
> with new kernel versions.

did old versions work?
if yes, what is the latest version that worked?

>                product: NV28 [GeForce4 Ti 4200 AGP 8x]
>                vendor: nVidia Corporation

are you using the same graphics drivers on the new failing kernel
as you did in the old working kernel?
Comment 5 Andrzej 2009-09-10 21:09:04 UTC
> what is the latest version that worked?
It seems to be 2.6.29. 
It's interesting, but the same kernel (2.6.27) which hangs with OpenSuSE works with Debian - so 2.6.27 might be good, but this is OpenSuse's issue.

> are you using the same graphics drivers on the new failing kernel
> as you did in the old working kernel?

Yes. I'm using version 96.43.13 which works with all kernels tested by me (from 2.6.18 to 2.6.30, tomorrow I will try to compile latest 2.6.31).

I also downloaded 2.6.30-1 image from Debian repository (to have a kernel not configured by me, without patches applied by me and without nVidia driver). However, it also hangs.
Comment 6 Andrzej 2009-09-11 12:02:32 UTC
>  I will try to compile latest 2.6.31
Update: 2.6.31 also hangs.
Comment 7 Ivan Kalvachev 2009-10-10 16:35:13 UTC
Do you have module/driver that loads firmware? (wifi?)
I'm having similar problem with e100 driver that started using external firmware since 2.6.29. The workaround is to embed the firmware into the kernel main blob.

Also take a look of linux-2.6/Documentation/power/basic-pm-debugging.txt
Comment 8 ykzhao 2009-10-12 15:12:09 UTC
Hi, Andrzej
    thanks for the test.
    Will you please do the hibernation test under console mode? (Enter the init level 3).
    If the issue still exists, please attach the output of lspci -vxxx, acpidump.
   
    Thanks.
Comment 9 Andrzej 2009-10-13 16:35:51 UTC
> Do you have module/driver that loads firmware? (wifi?)
I guess, that rt73usb loads some kind of binary firmware.

>  Will you please do the hibernation test under console mode?
In console mode it still hangs. I also tried to boot a very basic system (booting with init=/bin/bash and only mounting swap partition) and the issue still exists.
Comment 10 Andrzej 2009-10-13 16:38:07 UTC
Created attachment 23390 [details]
lspci and acpidump output

Output from lspci and acpidump.
Comment 11 Zhang Rui 2010-01-18 02:20:12 UTC
the problem still exists in 2.6.32, right?

please run "echo disk > /sys/power/state; dmesg > dmesg-hibernate" and see if you can get the dmesg after the hibernation failure.
If yes, or you can get the similar info in other ways, please attach the sys log after hibernation.
If no, please attach the screen shot when system hangs.
Comment 12 Zhang Rui 2011-04-19 07:51:02 UTC
bug closed as there is no response from the bug reporter.
please re-open it if the problem still exists in the latest upstream kernel.