gpu lockup at resume from hibernate

[Valentin Quequet]

Binary package hint: xorg

Xorg fails at resume from disk ; radeon open source driver

While suspend/resume to RAM function right, suspend/resume to disk fails indeed.

The X server makes many successive attempts to start but fails with the following syslog message appended.

I managed to log on a console, though. Then, killing this failing Xorg instance allowed the boot process (gdm or whichever script) to spawn a new and functional Xorg instance ; obviously not restoring my graphical user session.

Salut !

----- HERE COME THAT MESSAGE !!! -----

[ 1074.171002] tg3 0000:03:00.0: eth0: Link is down
[ 1075.482573] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 1077.928646] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 1079.999040] PM: Marking nosave pages: 000000000009d000 - 0000000000100000
[ 1079.999045] PM: Marking nosave pages: 00000000bfe90000 - 0000000100000000
[ 1080.000021] PM: Marking nosave pages: 00000000b4000000 - 00000000b8000000
[ 1080.000315] PM: Basic memory bitmaps created
[ 1080.000316] PM: Syncing filesystems ... done.
[ 1080.029785] Freezing user space processes ... (elapsed 0.02 seconds) done.
[ 1080.050105] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[ 1080.070119] PM: Preallocating image memory... done (allocated 626764 pages)
[ 1080.546991] PM: Allocated 2507056 kbytes in 0.47 seconds (5334.16 MB/s)
[ 1080.546994] Suspending console(s) (use no_console_suspend to debug)
[ 1080.563755] sd 2:0:0:0: [sda] Synchronizing SCSI cache
[ 1080.574796] HDA Intel 0000:02:00.1: PCI INT B disabled
[ 1080.680458] HDA Intel 0000:00:14.2: PCI INT A disabled
[ 1080.700082] PM: freeze of drv:HDA Intel dev:0000:00:14.2 complete after 125.934 msecs
[ 1080.750213] PM: freeze of drv:radeon dev:0000:02:00.0 complete after 176.106 msecs
[ 1080.750270] PM: freeze of drv:pcieport dev:0000:00:02.0 complete after 176.009 msecs
[ 1080.750291] PM: freeze of drv: dev:pci0000:00 complete after 176.002 msecs
[ 1080.750316] PM: freeze of devices complete after 203.047 msecs
[ 1080.750867] PM: late freeze of devices complete after 0.548 msecs
[ 1080.750929] ACPI: Preparing to enter system sleep state S4
[ 1081.100166] PM: Saving platform NVS memory
[ 1081.100232] Disabling non-boot CPUs ...
[ 1081.210072] CPU 1 is now offline
[ 1081.210401] Extended CMOS year: 2000
[ 1081.210466] PM: Creating hibernation image:
[ 1081.220049] PM: Need to copy 386033 pages
[ 1081.220049] PM: Normal pages needed: 386033 + 1024, available pages: 645593
[ 1081.220049] PM: Restoring platform NVS memory
[ 1081.220049] PCI-DMA: Resuming GART IOMMU
[ 1081.220049] PCI-DMA: Restoring GART aperture settings
[ 1081.220049] Extended CMOS year: 2000
[ 1081.220049] Enabling non-boot CPUs ...
[ 1081.220049] Booting Node 0 Processor 1 APIC 0x1
[ 1081.110427] Switch to broadcast mode on CPU1
[ 1081.390544] CPU1 is up
[ 1081.390679] ACPI: Waking up from system sleep state S4
[ 1081.400120] Switched to NOHz mode on CPU #1
[ 1082.030620] pcieport 0000:00:02.0: restoring config space at offset 0x7 (was 0x9191, writing 0x20009191)
[ 1082.030649] pcieport 0000:00:04.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x1f1)
[ 1082.030675] pcieport 0000:00:05.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x1f1)
[ 1082.030721] ahci 0000:00:11.0: restoring config space at offset 0x1 (was 0x2300403, writing 0x2300407)
[ 1082.030900] HDA Intel 0000:00:14.2: restoring config space at offset 0xf (was 0x10b, writing 0xb)
[ 1082.030918] HDA Intel 0000:00:14.2: restoring config space at offset 0x1 (was 0x4100006, writing 0x4100002)
[ 1082.031053] HDA Intel 0000:02:00.1: restoring config space at offset 0x1 (was 0x100007, writing 0x100003)
[ 1082.031103] tg3 0000:03:00.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100406)
[ 1082.031310] PM: early restore of devices complete after 0.766 msecs
[ 1082.116203] HDA Intel 0000:00:14.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 1082.116265] radeon 0000:02:00.0: setting latency timer to 64
[ 1082.116464] HDA Intel 0000:02:00.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[ 1082.116470] HDA Intel 0000:02:00.1: setting latency timer to 64
[ 1082.116508] HDA Intel 0000:02:00.1: irq 46 for MSI/MSI-X
[ 1082.135611] radeon 0000:02:00.0: WB enabled
[ 1082.145420] sd 2:0:0:0: [sda] Starting disk
[ 1082.151701] [drm] ring test succeeded in 1 usecs
[ 1082.151717] [drm] ib test succeeded in 0 usecs
[ 1082.174119] usb usb4: root hub lost power or was reset
[ 1082.174177] usb usb3: root hub lost power or was reset
[ 1082.174182] usb usb2: root hub lost power or was reset
[ 1082.174212] usb usb1: root hub lost power or was reset
[ 1082.218358] PM: restore of drv:HDA Intel dev:0000:00:14.2 complete after 102.169 msecs
[ 1082.312540] PM: restore of drv:battery dev:PNP0C0A:00 complete after 180.839 msecs
[ 1082.320150] PM: restore of drv:usb dev:usb1 complete after 176.012 msecs
[ 1082.320176] PM: restore of drv:hub dev:1-0:1.0 complete after 175.983 msecs
[ 1082.320184] PM: restore of drv: dev:ep_00 complete after 174.989 msecs
[ 1082.320188] PM: restore of drv: dev:ep_81 complete after 175.024 msecs
[ 1082.410142] PM: restore of drv:usb dev:usb3 complete after 264.905 msecs
[ 1082.410161] PM: restore of drv:hub dev:3-0:1.0 complete after 264.913 msecs
[ 1082.410168] PM: restore of drv: dev:ep_00 complete after 264.918 msecs
[ 1082.410172] PM: restore of drv: dev:ep_81 complete after 264.925 msecs
[ 1082.530093] PM: restore of drv:usb dev:usb4 complete after 384.837 msecs
[ 1082.530127] PM: restore of drv:hub dev:4-0:1.0 complete after 384.867 msecs
[ 1082.530144] PM: restore of drv: dev:ep_00 complete after 384.875 msecs
[ 1082.530174] PM: restore of drv: dev:ep_81 complete after 384.917 msecs
[ 1082.560092] PM: restore of drv:usb dev:usb2 complete after 414.889 msecs
[ 1082.560124] PM: restore of drv:hub dev:2-0:1.0 complete after 414.919 msecs
[ 1082.560156] PM: restore of drv: dev:ep_00 complete after 414.934 msecs
[ 1082.560193] PM: restore of drv: dev:ep_81 complete after 414.981 msecs
[ 1082.670097] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1082.670126] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1082.674797] ata4.00: configured for UDMA/100
[ 1082.712175] ata3.00: configured for UDMA/100
[ 1082.775078] PM: restore of drv:sd dev:2:0:0:0 complete after 629.649 msecs
[ 1082.775125] PM: restore of drv:scsi_disk dev:2:0:0:0 complete after 445.068 msecs
[ 1082.775132] PM: restore of drv:scsi_device dev:2:0:0:0 complete after 629.655 msecs
[ 1082.880106] usb 4-1: reset low speed USB device using ohci_hcd and address 2
[ 1083.080147] PM: restore of drv:radeon dev:0000:02:00.0 complete after 963.873 msecs
[ 1083.221056] PM: restore of drv:usb dev:4-1 complete after 1075.736 msecs
[ 1083.221102] PM: restore of drv: dev:ep_00 complete after 1075.761 msecs
[ 1083.221110] PM: restore of drv:usbhid dev:4-1:1.0 complete after 1075.786 msecs
[ 1083.221127] PM: restore of drv: dev:ep_81 complete after 1075.797 msecs
[ 1083.221134] PM: restore of drv:generic-usb dev:0003:045E:007D.0001 complete after 445.992 msecs
[ 1083.330089] usb 2-5: reset high speed USB device using ehci_hcd and address 3
[ 1083.518380] PM: restore of drv:usb dev:2-5 complete after 1373.089 msecs
[ 1083.518414] PM: restore of drv:uvcvideo dev:2-5:1.0 complete after 1373.112 msecs
[ 1083.518435] PM: restore of drv: dev:ep_00 complete after 1373.121 msecs
[ 1083.518444] PM: restore of drv: dev:ep_83 complete after 1373.136 msecs
[ 1083.518449] PM: restore of drv:uvcvideo dev:2-5:1.1 complete after 1373.138 msecs
[ 1083.518452] PM: restore of drv:video4linux dev:video0 complete after 297.262 msecs
[ 1083.560188] PM: restore of devices complete after 1444.189 msecs
[ 1083.560421] PM: Image restored successfully.
[ 1083.560423] Restarting tasks ... done.
[ 1083.573518] PM: Basic memory bitmaps freed
[ 1083.573525] video LNXVIDEO:00: Restoring backlight state
[ 1084.331042] tg3 0000:03:00.0: eth0: Link is down
[ 1088.331011] tg3 0000:03:00.0: eth0: Link is down
[ 1090.475857] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 1094.190133] radeon 0000:02:00.0: GPU lockup CP stall for more than 10020msec
[ 1094.190142] ------------[ cut here ]------------
[ 1094.190205] WARNING: at /build/buildd/linux-2.6.38/drivers/gpu/drm/radeon/radeon_fence.c:248 radeon_fence_wait+0x36f/0x3e0 [radeon]()
[ 1094.190213] Hardware name: eMachines G640
[ 1094.190218] GPU lockup (waiting for 0x000143F3 last fence id 0x000143F1)
[ 1094.190223] Modules linked in: ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 ip_tables x_tables snd_hrtimer binfmt_misc ipx p8023 kvm_amd kvm parport_pc ppdev snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_hwdep snd_pcm arc4 snd_seq_midi dm_crypt joydev snd_rawmidi snd_seq_midi_event snd_seq ath9k snd_timer snd_seq_device mac80211 snd ath9k_common ath9k_hw ath uvcvideo videodev soundcore cfg80211 sp5100_tco i2c_piix4 snd_page_alloc psmouse v4l2_compat_ioctl32 lp edac_core serio_raw edac_mce_amd acer_wmi k10temp sparse_keymap parport reiserfs raid10 raid456 async_pq async_xor async_memcpy async_raid6_recov raid6_pq async_tx raid1 raid0 multipath linear nbd dm_raid45 xor btrfs zlib_deflate libcrc32c radeon usbhid hid ttm drm_kms_helper drm video ahci tg3 i2c_algo_bit pata_atiixp libahci
[ 1094.190345] Pid: 1464, comm: Xorg Tainted: G W 2.6.38-7-generic #39-Ubuntu
[ 1094.190350] Call Trace:
[ 1094.190364] [<ffffffff8106573f>] ? warn_slowpath_common+0x7f/0xc0
[ 1094.190374] [<ffffffff81065836>] ? warn_slowpath_fmt+0x46/0x50
[ 1094.190418] [<ffffffffa012b66f>] ? radeon_fence_wait+0x36f/0x3e0 [radeon]
[ 1094.190428] [<ffffffff81087710>] ? autoremove_wake_function+0x0/0x40
[ 1094.190472] [<ffffffffa012bf51>] ? radeon_sync_obj_wait+0x11/0x20 [radeon]
[ 1094.190492] [<ffffffffa0093b0d>] ? ttm_bo_wait+0xfd/0x1b0 [ttm]
[ 1094.190510] [<ffffffffa0093e88>] ? ttm_bo_list_ref_sub+0x28/0x30 [ttm]
[ 1094.190555] [<ffffffffa01448e3>] ? radeon_gem_wait_idle_ioctl+0x93/0x110 [radeon]
[ 1094.190584] [<ffffffffa005b384>] ? drm_ioctl+0x3e4/0x4c0 [drm]
[ 1094.190630] [<ffffffffa0144850>] ? radeon_gem_wait_idle_ioctl+0x0/0x110 [radeon]
[ 1094.190639] [<ffffffff8100b7b1>] ? handle_signal+0x131/0x260
[ 1094.190647] [<ffffffff810156a1>] ? fpu_finit+0x21/0x40
[ 1094.190653] [<ffffffff810157ca>] ? init_fpu+0x4a/0x150
[ 1094.190663] [<ffffffff811751cf>] ? do_vfs_ioctl+0x8f/0x360
[ 1094.190669] [<ffffffff8100bd06>] ? sys_rt_sigreturn+0x106/0x140
[ 1094.190677] [<ffffffff81175531>] ? sys_ioctl+0x91/0xa0
[ 1094.190684] [<ffffffff8100c002>] ? system_call_fastpath+0x16/0x1b
[ 1094.190690] ---[ end trace 5a5d197966b56a30 ]---
[ 1094.191920] radeon 0000:02:00.0: GPU softreset
[ 1094.191927] radeon 0000:02:00.0: GRBM_STATUS=0xB2703828
[ 1094.191933] radeon 0000:02:00.0: GRBM_STATUS_SE0=0x1C000007
[ 1094.191938] radeon 0000:02:00.0: GRBM_STATUS_SE1=0x00000007
[ 1094.191944] radeon 0000:02:00.0: SRBM_STATUS=0x200000C0
[ 1094.192004] radeon 0000:02:00.0: GRBM_SOFT_RESET=0x00007F6B
[ 1094.192111] radeon 0000:02:00.0: GRBM_STATUS=0x00003828
[ 1094.192117] radeon 0000:02:00.0: GRBM_STATUS_SE0=0x00000007
[ 1094.192122] radeon 0000:02:00.0: GRBM_STATUS_SE1=0x00000007
[ 1094.192128] radeon 0000:02:00.0: SRBM_STATUS=0x200000C0
[ 1094.193133] radeon 0000:02:00.0: GPU reset succeed
[ 1094.214820] radeon 0000:02:00.0: WB enabled
[ 1094.230956] [drm] ring test succeeded in 1 usecs
[ 1094.230965] [drm] ib test succeeded in 1 usecs
[ 1094.901518] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id
[ 1105.160129] radeon 0000:02:00.0: GPU lockup CP stall for more than 10000msec

----- END OF MESSAGE !!! -----

ProblemType: Bug
DistroRelease: Ubuntu 11.04
Package: xorg 1:7.6+4ubuntu1
ProcVersionSignature: Ubuntu 2.6.38-7.39-generic 2.6.38
Uname: Linux 2.6.38-7-generic x86_64
Architecture: amd64
Date: Tue Apr 5 09:20:19 2011
DistroCodename: natty
DistroVariant: ubuntu
InstallationMedia: Ubuntu 10.04 LTS "Lucid Lynx" - Release amd64 (20100429)
ProcEnviron:
 LANGUAGE=fr_FR:en
 LANG=fr_FR.UTF-8
 SHELL=/bin/bash
SourcePackage: xorg
Symptom: display
UpgradeStatus: Upgraded to natty on 2010-11-23 (132 days ago)
version.libgl1-mesa-glx: libgl1-mesa-glx 7.10.1-0ubuntu3
version.xserver-xorg: xserver-xorg 1:7.6+4ubuntu1
---
Architecture: amd64
DistroRelease: Ubuntu 11.04
InstallationMedia: Ubuntu 10.04 LTS "Lucid Lynx" - Release amd64 (20100429)
Package: xserver-xorg-video-ati 1:6.14.0-0ubuntu4
PackageArchitecture: amd64
ProcEnviron:
 PATH=(custom, no user)
 LANG=fr_FR.UTF-8
 SHELL=/bin/bash
ProcVersionSignature: Ubuntu 2.6.38-8.40-generic 2.6.38.2
Tags: unity-2d natty
Uname: Linux 2.6.38-8-generic x86_64
UpgradeStatus: Upgraded to natty on 2010-11-23 (133 days ago)
UserGroups: pkcs11
---
Architecture: amd64
DistroRelease: Ubuntu 11.04
InstallationMedia: Ubuntu 10.04 LTS "Lucid Lynx" - Release amd64 (20100429)
Package: xserver-xorg-video-ati 1:6.14.0-0ubuntu4
PackageArchitecture: amd64
ProcEnviron:
 PATH=(custom, no user)
 LANG=fr_FR.UTF-8
 SHELL=/bin/bash
ProcVersionSignature: Ubuntu 2.6.38-7.39-generic 2.6.38
Tags: unity-2d natty
Uname: Linux 2.6.38-7-generic x86_64
UpgradeStatus: Upgraded to natty on 2010-11-23 (133 days ago)
UserGroups: pkcs11

[Bryce Harrington (bryce)]

[Actually it's not a Xorg failure but rather a gpu lockup with the kernel drm driver. Fixed up the title.]

Please reproduce the failure, and then run `apport-collect 751084` to attach some log files missing from your original report.

[Valentin Quequet (valentin-quequet)]

apport information

[Valentin Quequet (valentin-quequet)]

apport information

[Valentin Quequet (valentin-quequet)]

Hi,

I'm going to tell U more about this bug :

Two times I try hibernate/resume under linux-image-2.6.38-8-generic, two times I have a hard lockup.

Two times I try hibernate/resume under linux-image-2.6.38-7-generic, two times I can recover somehow :
    I manage to log on a console and to kill Xorg ; a new Xorg instance spawns and is functional (though I loose my pre-hibernation graphical user session).

Aussi, the suspend-to-ram/resume cycle works on those both kernels.

Ciao!

[Bryce Harrington (bryce)]

Alright, like I mentioned earlier hibernate issues generally tend to be kernel bugs, I'll go ahead and refile. I don't have further tips for debugging; you could try turning on drm debugging (install xdiagnose, it's the first checkbox; reboot for it to take effect and then collect 'dmesg'), which potentially might reveal something of interest. Hopefully the kernel guys can give you further guidance here.

[Launchpad Janitor (janitor)]

Status changed to 'Confirmed' because the bug affects multiple users.