suspend/resume stopped working

Sergey Senozhatsky sergey.senozhatsky at gmail.com
Tue Apr 12 01:28:06 PDT 2011


Hello,

On (04/11/11 17:49), Konrad Rzeszutek Wilk wrote:
> On Tue, Apr 12, 2011 at 12:02:28AM +0300, Sergey Senozhatsky wrote:
> > Hello,
> > Aborting (Ctrl-Brake) suspend to disk process (s2disk) brakes drm/radeon.
> 
> Can you try to revert 69a07f0b117a40fcc1a479358d8e1f41793617f2 just to see
> if that is the culprit.
>


No, seems this is not the case:

[  187.664959] snapshot_ioctl: ioctl '80083307' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  187.664973] Syncing filesystems ... done.
[  187.924511] Freezing user space processes ... (elapsed 0.01 seconds) done.
[  187.936806] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[  187.950171] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  187.950279] snapshot_ioctl: ioctl '40083306' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  187.950286] snapshot_ioctl: ioctl '40083303' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  187.950387] PM: Preallocating image memory... done (allocated 111404 pages)
[  188.090274] PM: Allocated 445616 kbytes in 0.14 seconds (3182.97 MB/s)
[  188.090276] Suspending console(s) (use no_console_suspend to debug)
[  188.092488] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  188.093460] HDA Intel 0000:01:00.1: PCI INT B disabled
[  188.093735] HDA Intel 0000:00:1b.0: PCI INT A disabled
[  188.431250] PM: freeze of devices complete after 340.164 msecs
[  188.432394] PM: late freeze of devices complete after 1.138 msecs
[  188.432631] ACPI: Preparing to enter system sleep state S4
[  188.459387] PM: Saving platform NVS memory
[  188.465680] Disabling non-boot CPUs ...
[  188.514554] CPU 1 is now offline
[  188.668819] CPU 2 is now offline
[  188.791921] CPU 3 is now offline
[  188.791925] lockdep: fixing up alternatives.
[  188.792860] Extended CMOS year: 2000
[  188.793031] PM: Creating hibernation image:
[  188.942484] PM: Need to copy 115235 pages
[  189.583797] PM: Hibernation image created (115235 pages copied)
[  188.793588] Extended CMOS year: 2000
[  188.794138] microcode: CPU0 updated to revision 0xc, date = 2010-06-10
[  188.794167] Enabling non-boot CPUs ...
[  188.801437] lockdep: fixing up alternatives.
[  188.801450] Booting Node 0 Processor 1 APIC 0x1
[  188.801455] smpboot cpu 1: start_ip = 98000
[  188.919038] Switched to NOHz mode on CPU #1
[  188.960028] NMI watchdog enabled, takes one hw-pmu counter.
[  188.960917] microcode: CPU1 updated to revision 0xc, date = 2010-06-10
[  188.960932] CPU1 is up
[  188.961183] lockdep: fixing up alternatives.
[  188.961188] Booting Node 0 Processor 2 APIC 0x4
[  188.961190] smpboot cpu 2: start_ip = 98000
[  189.078785] Switched to NOHz mode on CPU #2
[  189.119907] NMI watchdog enabled, takes one hw-pmu counter.
[  189.121519] microcode: CPU2 updated to revision 0xc, date = 2010-06-10
[  189.121538] CPU2 is up
[  189.122009] lockdep: fixing up alternatives.
[  189.122072] Booting Node 0 Processor 3 APIC 0x5
[  189.122076] smpboot cpu 3: start_ip = 98000
[  189.238541] Switched to NOHz mode on CPU #3
[  189.280097] NMI watchdog enabled, takes one hw-pmu counter.
[  189.281622] microcode: CPU3 updated to revision 0xc, date = 2010-06-10
[  189.281641] CPU3 is up
[  189.284963] ACPI: Waking up from system sleep state S4
[  189.358433] PM: early thaw of devices complete after 0.778 msecs
[  189.358884] pci 0000:00:1e.0: setting latency timer to 64
[  189.358966] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
[  189.359008] radeon 0000:01:00.0: power state changed by ACPI to D0
[  189.359017] radeon 0000:01:00.0: power state changed by ACPI to D0
[  189.359043] ahci 0000:00:1f.2: setting latency timer to 64
[  189.359115] radeon 0000:01:00.0: setting latency timer to 64
[  189.360927] tg3 0000:02:00.0: eth0: Link is down
[  189.368514] sd 0:0:0:0: [sda] Starting disk
[  189.371631] HDA Intel 0000:00:1b.0: BAR 0: set to [mem 0xb4100000-0xb4103fff 64bit] (PCI address [0xb4100000-0xb4103fff])
[  189.371683] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[  189.371738] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
[  189.371756] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
[  189.371820] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[  189.371838] HDA Intel 0000:00:1b.0: setting latency timer to 64
[  189.371901] HDA Intel 0000:01:00.1: BAR 0: set to [mem 0xb4020000-0xb4023fff 64bit] (PCI address [0xb4020000-0xb4023fff])
[  189.371968] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
[  189.372066] HDA Intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[  189.372083] HDA Intel 0000:01:00.1: setting latency timer to 64
[  189.372183] HDA Intel 0000:01:00.1: irq 44 for MSI/MSI-X
[  189.388906] radeon 0000:01:00.0: WB enabled
[  189.405421] [drm] ring test succeeded in 0 usecs
[  189.405492] [drm] ib test succeeded in 0 usecs
[  189.677756] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[  189.684411] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[  189.688617] ata1.00: configured for UDMA/133
[  189.712515] ata2.00: configured for UDMA/100
[  191.462224] PM: thaw of devices complete after 2107.311 msecs
[  191.463396] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463529] snapshot_ioctl: ioctl '80083307' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463641] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463651] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463995] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.464125] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  192.729019] Restarting tasks ... done.
[  207.645904] radeon 0000:01:00.0: GPU lockup CP stall for more than 10000msec
[  207.645909] ------------[ cut here ]------------
[  207.645921] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
[  207.645925] Hardware name: Aspire 5741G    
[  207.645928] GPU lockup (waiting for 0x0000033A last fence id 0x00000338)
[  207.645931] Modules linked in: microcode snd_seq_dummy snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device snd_hwdep snd_hda_codec_hdmi snd_hda_codec_realtek snd_pcm_oss snd_hda_intel snd_hda_codec snd_pcm sparse_keymap ac snd_timer
snd_page_alloc wmi snd_mixer_oss button battery psmouse snd broadcom tg3 libphy evdev soundcore ehci_hcd sr_mod usbcore cdrom sd_mod ahci
[  207.645987] Pid: 3729, comm: X Not tainted 2.6.39-rc3-dbg-00380-ge2d16b4-dirty #521
[  207.645990] Call Trace:
[  207.646001]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
[  207.646007]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
[  207.646013]  [<ffffffff81306686>] radeon_fence_wait+0x3c9/0x4a7
[  207.646021]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
[  207.646027]  [<ffffffff81306c75>] radeon_sync_obj_wait+0xc/0xe
[  207.646035]  [<ffffffff812d98d8>] ttm_bo_wait+0xb8/0x174
[  207.646043]  [<ffffffff8131a332>] radeon_gem_wait_idle_ioctl+0x7c/0xee
[  207.646050]  [<ffffffff812c6942>] drm_ioctl+0x29f/0x381
[  207.646055]  [<ffffffff8131a2b6>] ? radeon_gem_busy_ioctl+0x114/0x114
[  207.646064]  [<ffffffff810706d4>] ? trace_hardirqs_on_caller+0x10d/0x131
[  207.646069]  [<ffffffff81070705>] ? trace_hardirqs_on+0xd/0xf
[  207.646078]  [<ffffffff810e5de0>] ? remove_vma+0x57/0x5f
[  207.646084]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
[  207.646091]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
[  207.646098]  [<ffffffff81477d4a>] ? sysret_check+0x2e/0x69
[  207.646103]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
[  207.646109]  [<ffffffff81477d12>] system_call_fastpath+0x16/0x1b
[  207.646113] ---[ end trace 2090fe895342aa04 ]---
[  207.647421] radeon 0000:01:00.0: GPU softreset 
[  207.647425] radeon 0000:01:00.0:   GRBM_STATUS=0xB2703828
[  207.647429] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x1C000007
[  207.647432] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
[  207.647436] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
[  207.647496] radeon 0000:01:00.0:   GRBM_SOFT_RESET=0x00007F6B
[  207.647601] radeon 0000:01:00.0:   GRBM_STATUS=0x00003828
[  207.647605] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x00000007
[  207.647608] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
[  207.647612] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
[  207.648623] radeon 0000:01:00.0: GPU reset succeed
[  207.670209] radeon 0000:01:00.0: WB enabled
[  207.686710] [drm] ring test succeeded in 1 usecs
[  207.686758] [drm] ib test succeeded in 1 usecs
[  219.558069] radeon 0000:01:00.0: GPU lockup CP stall for more than 10020msec
[  219.558074] ------------[ cut here ]------------
[  219.558085] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
[  219.558090] Hardware name: Aspire 5741G    
[  219.558093] GPU lockup (waiting for 0x0000033C last fence id 0x0000033B)
[  219.558096] Modules linked in: microcode snd_seq_dummy snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device snd_hwdep snd_hda_codec_hdmi snd_hda_codec_realtek snd_pcm_oss snd_hda_intel snd_hda_codec snd_pcm sparse_keymap ac snd_timer
snd_page_alloc wmi snd_mixer_oss button battery psmouse snd broadcom tg3 libphy evdev soundcore ehci_hcd sr_mod usbcore cdrom sd_mod ahci
[  219.558151] Pid: 3729, comm: X Tainted: G        W   2.6.39-rc3-dbg-00380-ge2d16b4-dirty #521
[  219.558154] Call Trace:
[  219.558164]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
[  219.558170]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
[  219.558177]  [<ffffffff81306686>] radeon_fence_wait+0x3c9/0x4a7
[  219.558185]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
[  219.558191]  [<ffffffff81306c75>] radeon_sync_obj_wait+0xc/0xe
[  219.558198]  [<ffffffff812d98d8>] ttm_bo_wait+0xb8/0x174
[  219.558206]  [<ffffffff8131a332>] radeon_gem_wait_idle_ioctl+0x7c/0xee
[  219.558213]  [<ffffffff812c6942>] drm_ioctl+0x29f/0x381
[  219.558219]  [<ffffffff8131a2b6>] ? radeon_gem_busy_ioctl+0x114/0x114
[  219.558227]  [<ffffffff8106f9df>] ? lock_release_non_nested+0x91/0x247
[  219.558235]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
[  219.558241]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
[  219.558247]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
[  219.558253]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
[  219.558259]  [<ffffffff81471ebd>] ? retint_swapgs+0x13/0x1b
[  219.558264]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
[  219.558270]  [<ffffffff81477d12>] system_call_fastpath+0x16/0x1b
[  219.558275] ---[ end trace 2090fe895342aa05 ]---
[  219.559580] radeon 0000:01:00.0: GPU softreset 
[  219.559585] radeon 0000:01:00.0:   GRBM_STATUS=0xB2703828
[  219.559588] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x1C000007
[  219.559592] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
[  219.559596] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
[  219.559656] radeon 0000:01:00.0:   GRBM_SOFT_RESET=0x00007F6B
[  219.559761] radeon 0000:01:00.0:   GRBM_STATUS=0x00003828
[  219.559765] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x00000007
[  219.559768] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
[  219.559772] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
[  219.560783] radeon 0000:01:00.0: GPU reset succeed
[  219.582354] radeon 0000:01:00.0: WB enabled
[  219.598842] [drm] ring test succeeded in 1 usecs
[  219.598853] [drm] ib test succeeded in 1 usecs
[  231.773092] radeon 0000:01:00.0: GPU lockup CP stall for more than 10020msec
[  231.773096] ------------[ cut here ]------------
[  231.773108] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
[  231.773112] Hardware name: Aspire 5741G    
[  231.773115] GPU lockup (waiting for 0x00000342 last fence id 0x00000341)
[  231.773118] Modules linked in: microcode snd_seq_dummy snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device snd_hwdep snd_hda_codec_hdmi snd_hda_codec_realtek snd_pcm_oss snd_hda_intel snd_hda_codec snd_pcm sparse_keymap ac snd_timer
snd_page_alloc wmi snd_mixer_oss button battery psmouse snd broadcom tg3 libphy evdev soundcore ehci_hcd sr_mod usbcore cdrom sd_mod ahci
[  231.773176] Pid: 3729, comm: X Tainted: G        W   2.6.39-rc3-dbg-00380-ge2d16b4-dirty #521
[  231.773180] Call Trace:
[  231.773191]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
[  231.773197]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
[  231.773203]  [<ffffffff81306686>] radeon_fence_wait+0x3c9/0x4a7
[  231.773211]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
[  231.773217]  [<ffffffff81306c75>] radeon_sync_obj_wait+0xc/0xe
[  231.773225]  [<ffffffff812d98d8>] ttm_bo_wait+0xb8/0x174
[  231.773233]  [<ffffffff8131a332>] radeon_gem_wait_idle_ioctl+0x7c/0xee
[  231.773240]  [<ffffffff812c6942>] drm_ioctl+0x29f/0x381
[  231.773246]  [<ffffffff8131a2b6>] ? radeon_gem_busy_ioctl+0x114/0x114
[  231.773255]  [<ffffffff8106f9df>] ? lock_release_non_nested+0x91/0x247
[  231.773262]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
[  231.773268]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
[  231.773275]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
[  231.773282]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
[  231.773288]  [<ffffffff81471ebd>] ? retint_swapgs+0x13/0x1b
[  231.773293]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
[  231.773301]  [<ffffffff81477d12>] system_call_fastpath+0x16/0x1b
[  231.773305] ---[ end trace 2090fe895342aa06 ]---
[  188.801455] smpboot cpu 1: start_ip = 98000
[  188.919038] Switched to NOHz mode on CPU #1
[  188.960028] NMI watchdog enabled, takes one hw-pmu counter.
[  188.960917] microcode: CPU1 updated to revision 0xc, date = 2010-06-10
[  188.960932] CPU1 is up
[  188.961183] lockdep: fixing up alternatives.
[  188.961188] Booting Node 0 Processor 2 APIC 0x4
[  188.961190] smpboot cpu 2: start_ip = 98000
[  189.078785] Switched to NOHz mode on CPU #2
[  189.119907] NMI watchdog enabled, takes one hw-pmu counter.
[  189.121519] microcode: CPU2 updated to revision 0xc, date = 2010-06-10
[  189.121538] CPU2 is up
[  189.122009] lockdep: fixing up alternatives.
[  189.122072] Booting Node 0 Processor 3 APIC 0x5
[  189.122076] smpboot cpu 3: start_ip = 98000
[  189.238541] Switched to NOHz mode on CPU #3
[  189.280097] NMI watchdog enabled, takes one hw-pmu counter.
[  189.281622] microcode: CPU3 updated to revision 0xc, date = 2010-06-10
[  189.281641] CPU3 is up
[  189.284963] ACPI: Waking up from system sleep state S4
[  189.358433] PM: early thaw of devices complete after 0.778 msecs
[  189.358884] pci 0000:00:1e.0: setting latency timer to 64
[  189.358966] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
[  189.359008] radeon 0000:01:00.0: power state changed by ACPI to D0
[  189.359017] radeon 0000:01:00.0: power state changed by ACPI to D0
[  189.359043] ahci 0000:00:1f.2: setting latency timer to 64
[  189.359115] radeon 0000:01:00.0: setting latency timer to 64
[  189.360927] tg3 0000:02:00.0: eth0: Link is down
[  189.368514] sd 0:0:0:0: [sda] Starting disk
[  189.371631] HDA Intel 0000:00:1b.0: BAR 0: set to [mem 0xb4100000-0xb4103fff 64bit] (PCI address [0xb4100000-0xb4103fff])
[  189.371683] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
[  189.371738] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
[  189.371756] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
[  189.371820] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[  189.371838] HDA Intel 0000:00:1b.0: setting latency timer to 64
[  189.371901] HDA Intel 0000:01:00.1: BAR 0: set to [mem 0xb4020000-0xb4023fff 64bit] (PCI address [0xb4020000-0xb4023fff])
[  189.371968] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
[  189.372066] HDA Intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[  189.372083] HDA Intel 0000:01:00.1: setting latency timer to 64
[  189.372183] HDA Intel 0000:01:00.1: irq 44 for MSI/MSI-X
[  189.388906] radeon 0000:01:00.0: WB enabled
[  189.405421] [drm] ring test succeeded in 0 usecs
[  189.405492] [drm] ib test succeeded in 0 usecs
[  189.677756] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[  189.684411] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[  189.688617] ata1.00: configured for UDMA/133
[  189.712515] ata2.00: configured for UDMA/100
[  191.462224] PM: thaw of devices complete after 2107.311 msecs
[  191.463396] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463529] snapshot_ioctl: ioctl '80083307' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463641] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463651] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.463995] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  191.464125] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
[  192.729019] Restarting tasks ... done.
[  207.645904] radeon 0000:01:00.0: GPU lockup CP stall for more than 10000msec
[  207.645909] ------------[ cut here ]------------
[  207.645921] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
[  207.645925] Hardware name: Aspire 5741G    
[  207.645928] GPU lockup (waiting for 0x0000033A last fence id 0x00000338)
[  207.645931] Modules linked in: microcode snd_seq_dummy snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device snd_hwdep snd_hda_codec_hdmi snd_hda_codec_realtek snd_pcm_oss snd_hda_intel snd_hda_codec snd_pcm sparse_keymap ac snd_timer
snd_page_alloc wmi snd_mixer_oss button battery psmouse snd broadcom tg3 libphy evdev soundcore ehci_hcd sr_mod usbcore cdrom sd_mod ahci
[  207.645987] Pid: 3729, comm: X Not tainted 2.6.39-rc3-dbg-00380-ge2d16b4-dirty #521
[  207.645990] Call Trace:
[  207.646001]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
[  207.646007]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
[  207.646013]  [<ffffffff81306686>] radeon_fence_wait+0x3c9/0x4a7
[  207.646021]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
[  207.646027]  [<ffffffff81306c75>] radeon_sync_obj_wait+0xc/0xe
[  207.646035]  [<ffffffff812d98d8>] ttm_bo_wait+0xb8/0x174
[  207.646043]  [<ffffffff8131a332>] radeon_gem_wait_idle_ioctl+0x7c/0xee
[  207.646050]  [<ffffffff812c6942>] drm_ioctl+0x29f/0x381
[  207.646055]  [<ffffffff8131a2b6>] ? radeon_gem_busy_ioctl+0x114/0x114
[  207.646064]  [<ffffffff810706d4>] ? trace_hardirqs_on_caller+0x10d/0x131
[  207.646069]  [<ffffffff81070705>] ? trace_hardirqs_on+0xd/0xf
[  207.646078]  [<ffffffff810e5de0>] ? remove_vma+0x57/0x5f
[  207.646084]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
[  207.646091]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
[  207.646098]  [<ffffffff81477d4a>] ? sysret_check+0x2e/0x69
[  207.646103]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
[  207.646109]  [<ffffffff81477d12>] system_call_fastpath+0x16/0x1b
[  207.646113] ---[ end trace 2090fe895342aa04 ]---
[  207.647421] radeon 0000:01:00.0: GPU softreset 
[  207.647425] radeon 0000:01:00.0:   GRBM_STATUS=0xB2703828
[  207.647429] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x1C000007
[  207.647432] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
[  207.647436] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
[  207.647496] radeon 0000:01:00.0:   GRBM_SOFT_RESET=0x00007F6B
[  207.647601] radeon 0000:01:00.0:   GRBM_STATUS=0x00003828
[  207.647605] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x00000007
[  207.647608] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
[  207.647612] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
[  207.648623] radeon 0000:01:00.0: GPU reset succeed
[  207.670209] radeon 0000:01:00.0: WB enabled
[  207.686710] [drm] ring test succeeded in 1 usecs
[  207.686758] [drm] ib test succeeded in 1 usecs
[  219.558069] radeon 0000:01:00.0: GPU lockup CP stall for more than 10020msec



	Sergey
 

> > Please see the logs below:
> > 
> > [..]
> > [  130.722206] snapshot_ioctl: ioctl '80083307' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  130.722220] Syncing filesystems ... done.
> > [  131.022029] Freezing user space processes ... (elapsed 0.01 seconds) done.
> > [  131.034312] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
> > [  131.047640] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  131.047752] snapshot_ioctl: ioctl '40083306' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  131.047759] snapshot_ioctl: ioctl '40083303' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  131.047871] PM: Preallocating image memory... done (allocated 82641 pages)
> > [  131.179814] PM: Allocated 330564 kbytes in 0.13 seconds (2542.80 MB/s)
> > [  131.179817] Suspending console(s) (use no_console_suspend to debug)
> > [  131.182797] sd 0:0:0:0: [sda] Synchronizing SCSI cache
> > [  131.183264] HDA Intel 0000:01:00.1: PCI INT B disabled
> > [  131.183802] HDA Intel 0000:00:1b.0: PCI INT A disabled
> > [  131.504654] PM: freeze of devices complete after 323.233 msecs
> > [  131.506566] PM: late freeze of devices complete after 1.904 msecs
> > [  131.506773] ACPI: Preparing to enter system sleep state S4
> > [  131.531112] PM: Saving platform NVS memory
> > [  131.534328] Disabling non-boot CPUs ...
> > [  131.580177] CPU 1 is now offline
> > [  131.622812] CPU 2 is now offline
> > [  131.754363] CPU 3 is now offline
> > [  131.754367] lockdep: fixing up alternatives.
> > [  131.755297] Extended CMOS year: 2000
> > [  131.755468] PM: Creating hibernation image:
> > [  131.908665] PM: Need to copy 86670 pages
> > [  132.509928] PM: Hibernation image created (86670 pages copied)
> > [  131.756026] Extended CMOS year: 2000
> > [  131.756578] microcode: CPU0 updated to revision 0xc, date = 2010-06-10
> > [  131.756606] Enabling non-boot CPUs ...
> > [  131.763890] lockdep: fixing up alternatives.
> > [  131.763904] Booting Node 0 Processor 1 APIC 0x1
> > [  131.763908] smpboot cpu 1: start_ip = 98000
> > [  131.881690] Switched to NOHz mode on CPU #1
> > [  131.923128] NMI watchdog enabled, takes one hw-pmu counter.
> > [  131.924625] microcode: CPU1 updated to revision 0xc, date = 2010-06-10
> > [  131.924656] CPU1 is up
> > [  131.924949] lockdep: fixing up alternatives.
> > [  131.924959] Booting Node 0 Processor 2 APIC 0x4
> > [  131.924962] smpboot cpu 2: start_ip = 98000
> > [  132.041707] Switched to NOHz mode on CPU #2
> > [  132.083285] NMI watchdog enabled, takes one hw-pmu counter.
> > [  132.084938] microcode: CPU2 updated to revision 0xc, date = 2010-06-10
> > [  132.084950] CPU2 is up
> > [  132.085415] lockdep: fixing up alternatives.
> > [  132.085460] Booting Node 0 Processor 3 APIC 0x5
> > [  132.085463] smpboot cpu 3: start_ip = 98000
> > [  132.201793] Switched to NOHz mode on CPU #3
> > [  132.244130] NMI watchdog enabled, takes one hw-pmu counter.
> > [  132.245820] microcode: CPU3 updated to revision 0xc, date = 2010-06-10
> > [  132.245834] CPU3 is up
> > [  132.248617] ACPI: Waking up from system sleep state S4
> > [  132.324868] PM: early thaw of devices complete after 0.540 msecs
> > [  132.325292] pci 0000:00:1e.0: setting latency timer to 64
> > [  132.325386] radeon 0000:01:00.0: power state changed by ACPI to D0
> > [  132.325470] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
> > [  132.325510] radeon 0000:01:00.0: power state changed by ACPI to D0
> > [  132.325547] ahci 0000:00:1f.2: setting latency timer to 64
> > [  132.325630] radeon 0000:01:00.0: setting latency timer to 64
> > [  132.325681] sd 0:0:0:0: [sda] Starting disk
> > [  132.338376] HDA Intel 0000:00:1b.0: BAR 0: set to [mem 0xb4100000-0xb4103fff 64bit] (PCI address [0xb4100000-0xb4103fff])
> > [  132.338381] HDA Intel 0000:01:00.1: BAR 0: set to [mem 0xb4020000-0xb4023fff 64bit] (PCI address [0xb4020000-0xb4023fff])
> > [  132.338413] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
> > [  132.338457] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
> > [  132.338470] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
> > [  132.338501] HDA Intel 0000:01:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
> > [  132.338511] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
> > [  132.338514] HDA Intel 0000:01:00.1: setting latency timer to 64
> > [  132.338522] HDA Intel 0000:00:1b.0: setting latency timer to 64
> > [  132.338612] HDA Intel 0000:00:1b.0: irq 43 for MSI/MSI-X
> > [  132.338615] HDA Intel 0000:01:00.1: irq 44 for MSI/MSI-X
> > [  132.350073] radeon 0000:01:00.0: WB enabled
> > [  132.366610] [drm] ring test succeeded in 0 usecs
> > [  132.366676] [drm] ib test succeeded in 0 usecs
> > [  132.645181] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> > [  132.649348] ata1.00: configured for UDMA/133
> > [  132.658490] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> > [  132.693686] ata2.00: configured for UDMA/100
> > [  134.443057] PM: thaw of devices complete after 2117.746 msecs
> > [  134.444533] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  134.444668] snapshot_ioctl: ioctl '80083307' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  134.444779] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  134.444790] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  134.445134] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  134.445321] snapshot_ioctl: ioctl '80083308' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  135.446441] tg3 0000:02:00.0: eth0: Link is down
> > [  136.068592] snapshot_deprecated_ioctl: 6826 callbacks suppressed
> > [  136.068600] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
> > [  136.068604] Restarting tasks ... done.
> > [  151.020423] radeon 0000:01:00.0: GPU lockup CP stall for more than 10000msec
> > [  151.020428] ------------[ cut here ]------------
> > [  151.020439] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
> > [  151.020443] Hardware name: Aspire 5741G    
> > [  151.020447] GPU lockup (waiting for 0x00000B87 last fence id 0x00000B85)
> > [  151.020450] Modules linked in: ipv6 microcode snd_hda_codec_hdmi snd_seq_dummy snd_hwdep snd_hda_codec_realtek snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device rndis_host snd_hda_intel snd_pcm_oss cdc_ether usbnet snd_hda_codec
> > snd_pcm snd_timer snd_page_alloc snd_mixer_oss sparse_keymap mii ac battery snd wmi button broadcom psmouse tg3 libphy evdev soundcore ehci_hcd sr_mod cdrom usbcore sd_mod ahci
> > [  151.020515] Pid: 3772, comm: X Not tainted 2.6.39-rc2-dbg-git4-00555-g2fc20f7-dirty #520
> > [  151.020519] Call Trace:
> > [  151.020530]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
> > [  151.020536]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
> > [  151.020542]  [<ffffffff8130673e>] radeon_fence_wait+0x3c9/0x4a7
> > [  151.020550]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
> > [  151.020557]  [<ffffffff81306d2d>] radeon_sync_obj_wait+0xc/0xe
> > [  151.020564]  [<ffffffff812d97e8>] ttm_bo_wait+0xb8/0x174
> > [  151.020573]  [<ffffffff8131a3ea>] radeon_gem_wait_idle_ioctl+0x7c/0xee
> > [  151.020580]  [<ffffffff812c6852>] drm_ioctl+0x29f/0x381
> > [  151.020585]  [<ffffffff8131a36e>] ? radeon_gem_busy_ioctl+0x114/0x114
> > [  151.020594]  [<ffffffff810706d4>] ? trace_hardirqs_on_caller+0x10d/0x131
> > [  151.020600]  [<ffffffff81070705>] ? trace_hardirqs_on+0xd/0xf
> > [  151.020609]  [<ffffffff810e5de0>] ? remove_vma+0x57/0x5f
> > [  151.020615]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
> > [  151.020622]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
> > [  151.020630]  [<ffffffff81477e0a>] ? sysret_check+0x2e/0x69
> > [  151.020636]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
> > [  151.020641]  [<ffffffff81477dd2>] system_call_fastpath+0x16/0x1b
> > [  151.020646] ---[ end trace ef966166f47d63ae ]---
> > [  151.021954] radeon 0000:01:00.0: GPU softreset 
> > [  151.021959] radeon 0000:01:00.0:   GRBM_STATUS=0xB2703828
> > [  151.021962] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x1C000007
> > [  151.021966] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
> > [  151.021970] radeon 0000:01:00.0:   SRBM_STATUS=0x200002C0
> > [  151.022029] radeon 0000:01:00.0:   GRBM_SOFT_RESET=0x00007F6B
> > [  151.022134] radeon 0000:01:00.0:   GRBM_STATUS=0x00003828
> > [  151.022138] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x00000007
> > [  151.022141] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
> > [  151.022145] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
> > [  151.023156] radeon 0000:01:00.0: GPU reset succeed
> > [  151.044717] radeon 0000:01:00.0: WB enabled
> > [  151.061210] [drm] ring test succeeded in 0 usecs
> > [  151.061258] [drm] ib test succeeded in 1 usecs
> > [  162.914959] radeon 0000:01:00.0: GPU lockup CP stall for more than 10020msec
> > [  162.914964] ------------[ cut here ]------------
> > [  162.914976] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
> > [  162.914980] Hardware name: Aspire 5741G    
> > [  162.914983] GPU lockup (waiting for 0x00000B89 last fence id 0x00000B88)
> > [  162.914986] Modules linked in: ipv6 microcode snd_hda_codec_hdmi snd_seq_dummy snd_hwdep snd_hda_codec_realtek snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device rndis_host snd_hda_intel snd_pcm_oss cdc_ether usbnet snd_hda_codec
> > snd_pcm snd_timer snd_page_alloc snd_mixer_oss sparse_keymap mii ac battery snd wmi button broadcom psmouse tg3 libphy evdev soundcore ehci_hcd sr_mod cdrom usbcore sd_mod ahci
> > [  162.915049] Pid: 3772, comm: X Tainted: G        W   2.6.39-rc2-dbg-git4-00555-g2fc20f7-dirty #520
> > [  162.915052] Call Trace:
> > [  162.915062]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
> > [  162.915068]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
> > [  162.915075]  [<ffffffff8130673e>] radeon_fence_wait+0x3c9/0x4a7
> > [  162.915083]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
> > [  162.915089]  [<ffffffff81306d2d>] radeon_sync_obj_wait+0xc/0xe
> > [  162.915097]  [<ffffffff812d97e8>] ttm_bo_wait+0xb8/0x174
> > [  162.915105]  [<ffffffff8131a3ea>] radeon_gem_wait_idle_ioctl+0x7c/0xee
> > [  162.915112]  [<ffffffff812c6852>] drm_ioctl+0x29f/0x381
> > [  162.915118]  [<ffffffff8131a36e>] ? radeon_gem_busy_ioctl+0x114/0x114
> > [  162.915126]  [<ffffffff8106f9df>] ? lock_release_non_nested+0x91/0x247
> > [  162.915134]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
> > [  162.915140]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
> > [  162.915147]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
> > [  162.915154]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
> > [  162.915160]  [<ffffffff81471f7d>] ? retint_swapgs+0x13/0x1b
> > [  162.915165]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
> > [  162.915171]  [<ffffffff81477dd2>] system_call_fastpath+0x16/0x1b
> > [  162.915176] ---[ end trace ef966166f47d63af ]---
> > [  162.916478] radeon 0000:01:00.0: GPU softreset 
> > [  162.916482] radeon 0000:01:00.0:   GRBM_STATUS=0xB2703828
> > [  162.916486] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x1C000007
> > [  162.916490] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
> > [  162.916494] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
> > [  162.916551] radeon 0000:01:00.0:   GRBM_SOFT_RESET=0x00007F6B
> > [  162.916657] radeon 0000:01:00.0:   GRBM_STATUS=0x00003828
> > [  162.916660] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x00000007
> > [  162.916664] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
> > [  162.916668] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
> > [  162.917678] radeon 0000:01:00.0: GPU reset succeed
> > [  162.939238] radeon 0000:01:00.0: WB enabled
> > [  162.955734] [drm] ring test succeeded in 1 usecs
> > [  162.955745] [drm] ib test succeeded in 1 usecs
> > [  175.072852] radeon 0000:01:00.0: GPU lockup CP stall for more than 10043msec
> > [  175.072857] ------------[ cut here ]------------
> > [  175.072869] WARNING: at drivers/gpu/drm/radeon/radeon_fence.c:246 radeon_fence_wait+0x3c9/0x4a7()
> > [  175.072873] Hardware name: Aspire 5741G    
> > [  175.072876] GPU lockup (waiting for 0x00000B8D last fence id 0x00000B8C)
> > [  175.072879] Modules linked in: ipv6 microcode snd_hda_codec_hdmi snd_seq_dummy snd_hwdep snd_hda_codec_realtek snd_seq_oss snd_seq_midi_event snd_seq snd_seq_device rndis_host snd_hda_intel snd_pcm_oss cdc_ether usbnet snd_hda_codec
> > snd_pcm snd_timer snd_page_alloc snd_mixer_oss sparse_keymap mii ac battery snd wmi button broadcom psmouse tg3 libphy evdev soundcore ehci_hcd sr_mod cdrom usbcore sd_mod ahci
> > [  175.072943] Pid: 3772, comm: X Tainted: G        W   2.6.39-rc2-dbg-git4-00555-g2fc20f7-dirty #520
> > [  175.072947] Call Trace:
> > [  175.072957]  [<ffffffff8103e2ac>] warn_slowpath_common+0x7e/0x96
> > [  175.072963]  [<ffffffff8103e358>] warn_slowpath_fmt+0x41/0x43
> > [  175.072969]  [<ffffffff8130673e>] radeon_fence_wait+0x3c9/0x4a7
> > [  175.072977]  [<ffffffff8105cb72>] ? __init_waitqueue_head+0x46/0x46
> > [  175.072984]  [<ffffffff81306d2d>] radeon_sync_obj_wait+0xc/0xe
> > [  175.072991]  [<ffffffff812d97e8>] ttm_bo_wait+0xb8/0x174
> > [  175.072999]  [<ffffffff8131a3ea>] radeon_gem_wait_idle_ioctl+0x7c/0xee
> > [  175.073007]  [<ffffffff812c6852>] drm_ioctl+0x29f/0x381
> > [  175.073012]  [<ffffffff8131a36e>] ? radeon_gem_busy_ioctl+0x114/0x114
> > [  175.073021]  [<ffffffff8106f9df>] ? lock_release_non_nested+0x91/0x247
> > [  175.073029]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
> > [  175.073035]  [<ffffffff81001cee>] ? sys_rt_sigreturn+0x86/0x21c
> > [  175.073042]  [<ffffffff81113f83>] do_vfs_ioctl+0x46c/0x4ad
> > [  175.073048]  [<ffffffff811057ef>] ? fget_light+0x11f/0x30b
> > [  175.073054]  [<ffffffff81471f7d>] ? retint_swapgs+0x13/0x1b
> > [  175.073059]  [<ffffffff81114015>] sys_ioctl+0x51/0x74
> > [  175.073065]  [<ffffffff81477dd2>] system_call_fastpath+0x16/0x1b
> > [  175.073070] ---[ end trace ef966166f47d63b0 ]---
> > [  175.074371] radeon 0000:01:00.0: GPU softreset 
> > [  175.074375] radeon 0000:01:00.0:   GRBM_STATUS=0xB2703828
> > [  175.074379] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x1C000007
> > [  175.074383] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
> > [  175.074387] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
> > [  175.074444] radeon 0000:01:00.0:   GRBM_SOFT_RESET=0x00007F6B
> > [  175.074550] radeon 0000:01:00.0:   GRBM_STATUS=0x00003828
> > [  175.074553] radeon 0000:01:00.0:   GRBM_STATUS_SE0=0x00000007
> > [  175.074557] radeon 0000:01:00.0:   GRBM_STATUS_SE1=0x00000007
> > [  175.074561] radeon 0000:01:00.0:   SRBM_STATUS=0x200000C0
> > [  175.075571] radeon 0000:01:00.0: GPU reset succeed
> > [  175.097128] radeon 0000:01:00.0: WB enabled
> > [  175.113623] [drm] ring test succeeded in 1 usecs
> > [...] and so on.
> > 
> > 
> > kernel: .39-rc2-*
> > 
> > 
> > 	Sergey
> 
> 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 316 bytes
Desc: not available
URL: <http://lists.freedesktop.org/archives/dri-devel/attachments/20110412/af8fc8ee/attachment-0001.pgp>


More information about the dri-devel mailing list