Re: suspend/resume stopped working
From: Sergey Senozhatsky
Date: Tue Apr 12 2011 - 04:27:46 EST
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
>
>
Attachment:
pgp00000.pgp
Description: PGP signature