suspend/resume stopped working

Konrad Rzeszutek Wilk konrad.wilk at oracle.com
Mon Apr 11 14:49:45 PDT 2011


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.

> 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




More information about the dri-devel mailing list