Sometimes it doesn't resume from suspend

AnnS · 2011-06-30 05:17:00

Hey! As you may guess (!), I'm new to this. But I love the idea of learning.

Anyway, I have a laptop Acer Aspire 7741G-6426 and I'm having a little problem. See, I first installed XFCE4 with SLIM as a WM, the problem was that I couldn't resume from suspend (or sleep), all I got was a black screen. After that, I enabled autologin to XFCE, and just to tried one more time, I suspended, and the problem was 'solved'. I could resume without problems.

I'm on a fresh install now, with KDE and the same problem happened before I enabled autologin in KDM (before enabling it, the same problem described above occurred). I can suspend and resume. That is, most of the time. An hour ago I suspended the laptop, and my surprise was to find the black screen again when trying to resume. When I resumed, the disk seemed to load something and then stopped and all I could see was a black screen, the same as before. The only thing I can do when that happens is to hard shutdown the PC.

A few minutes ago I tried again, and resumed without problems. But I'm afraid it might happen again and I might lose something important. I need to find a solution.

Also, I have an AMD 6550M video card and I'm using the Catalyst driver (because the open source made the laptop too hot).

I don't know if this is related, but if I run "lsmod | grep radeon", this is the output:

radeon                989731  0 
ttm                    54560  1 radeon
drm_kms_helper         27529  1 radeon
drm                   181991  3 radeon,ttm,drm_kms_helper
i2c_algo_bit            5239  1 radeon
i2c_core               20165  6 videodev,radeon,drm_kms_helper,drm,i2c_algo_bit,i2c_i801

Is it normal that the radeon driver is loaded? Isn't that the open source one, which I didn't installed?
Anyway, does anyone have any idea of what this is?
I don't know what kind of information I should give.

Oh, and I've been reading some logs (which I'm making myself believe I can understand, haha), and in pm-suspend.log, almost everything says "resume suspend: success", except for:

Running hook /etc/pm/sleep.d/90alsa resume suspend:
ALSA lib pulse.c:229:(pulse_connect) PulseAudio: Unable to connect: Connection refused

aplay: main:660: audio open error: Connection refused

In kernel.log, there are many (specially 'dhcpcd not running'):

Jun 29 20:03:43 localhost kernel: [ 8233.952493] ath: txq: ffff88012c2068d0 axq_qnum: 2, mac80211_qnum: 2 axq_link: ffff8800bac25ea8 pending frames: 1 axq_acq empty: 1 stopped: 0 axq_depth: 0  Attempting to restart tx logic.
Jun 29 23:34:01 localhost kernel: [    6.727474] intel ips 0000:00:1f.6: failed to get i915 symbols, graphics turbo disabled
Jun 29 23:34:01 localhost kernel: [    6.904533] acer_wmi: Unable to detect available WMID devices
Jun 29 23:34:14 localhost dhcpcd[955]: dhcpcd not running
Jun 29 23:34:30 localhost pulseaudio[1155]: pid.c: Daemon already running.

And also, sorry for my english (and such a long post)!
Thanks anyway.

Cdh · 2011-06-30 07:07:43

Indeed it seems to be related to this from me: https://bbs.archlinux.org/viewtopic.php?id=117276

I believe now that it all has to do with fglrx. I have posted this in the aticchtml bugtracker also:

I have identified three things that can happen on wakeup:

1) total hang: Not even the hard drive spins up. This didn't happen anymore
when I added nopat to the kernel line. With kernel 3.0-rc5 it seems that it
doesn't happen without nopat either but I didn't often test it.

2) "ASIC hang happened" is written to the log. I'll attach that. Everything
works except for X and changing from X to tty. I either have to ssh into the
laptop or reboot it with sysrq keys. This happens sometimes.

3) No error message at all in any log I searched, but a black screen, changing
to tty not possible, X is consuming 100% CPU and is invulnerable to "kill -9"
but it is in state R+, so the kernel doesn't recognize it as being stuck. Here
I also have to reboot using sysrq or via ssh.
I have not really measured it but I'd say this happens on 1/3 of tries to wake
up on my laptop.

This is the "ASIC hang happened" log...

2011-06-28T08:49:44.080395+02:00 localhost kernel: [ 7045.639214] [fglrx] ASIC hang happened
2011-06-28T08:49:44.080412+02:00 localhost kernel: [ 7045.639218] Pid: 871, comm: Xorg Tainted: P            3.0.0-rc4-mainline #2
2011-06-28T08:49:44.080413+02:00 localhost kernel: [ 7045.639220] Call Trace:
2011-06-28T08:49:44.080417+02:00 localhost kernel: [ 7045.639266]  [<ffffffffa045dd6e>] KCL_DEBUG_OsDump+0xe/0x10 [fglrx]
2011-06-28T08:49:44.080420+02:00 localhost kernel: [ 7045.639284]  [<ffffffffa046b18c>] firegl_hardwareHangRecovery+0x1c/0x50 [fglrx]
2011-06-28T08:49:44.080428+02:00 localhost kernel: [ 7045.639317]  [<ffffffffa04f2419>] ? _ZN4Asic9WaitUntil15ResetASICIfHungEv+0x9/0x10 [fglrx]
2011-06-28T08:49:44.080430+02:00 localhost kernel: [ 7045.639348]  [<ffffffffa04f23cc>] ? _ZN4Asic9WaitUntil15WaitForCompleteEv+0x6c/0xb0 [fglrx]
2011-06-28T08:49:44.080432+02:00 localhost kernel: [ 7045.639364]  [<ffffffffa053ed7f>] ? _ZN13BaseUVDEngineI18mmEngineRS780_UVD2E4idleEv+0x7f/0xc0 [fglrx]
2011-06-28T08:49:44.080436+02:00 localhost kernel: [ 7045.639396]  [<ffffffffa04f155c>] ? _ZN15ExecutableUnits35flush_all_and_invalidate_HDP_cachesE12_QS_CP_RING_+0xbc/0xe0 [fglrx]
2011-06-28T08:49:44.080441+02:00 localhost kernel: [ 7045.639428]  [<ffffffffa04f1355>] ? _ZN15ExecutableUnits10CPRingIdleE15idle_WaitMethod12_QS_CP_RING_+0x105/0x1a0 [fglrx]
2011-06-28T08:49:44.080443+02:00 localhost kernel: [ 7045.639459]  [<ffffffffa04fb807>] ? _ZN11AsicCypress21initializeMicroEngineEv+0x147/0x160 [fglrx]
2011-06-28T08:49:44.080445+02:00 localhost kernel: [ 7045.639475]  [<ffffffffa053f026>] ? _ZN13BaseUVDEngineI18mmEngineRS780_UVD2E14SwitchEngineHWEN8mmEngine15MM_ENGINE_STATEE+0x26/0x1d0 [fglrx]
2011-06-28T08:49:44.080448+02:00 localhost kernel: [ 7045.639507]  [<ffffffffa04ec33a>] ? _ZN18mmEnginesContainer13assertEnginesEb+0x5a/0xa0 [fglrx]
2011-06-28T08:49:44.080450+02:00 localhost kernel: [ 7045.639538]  [<ffffffffa04f0e78>] ? _ZN15ExecutableUnits17assertPrimaryRingEb+0x1f8/0x210 [fglrx]
2011-06-28T08:49:44.080451+02:00 localhost kernel: [ 7045.639569]  [<ffffffffa04f872a>] ? _ZN8AsicR6009assertPM4Eb+0x4a/0x70 [fglrx]
2011-06-28T08:49:44.080453+02:00 localhost kernel: [ 7045.639589]  [<ffffffffa0488350>] ? firegl_cmmqs_disabledriver+0xf0/0xf0 [fglrx]
2011-06-28T08:49:44.080455+02:00 localhost kernel: [ 7045.639618]  [<ffffffffa04cd0aa>] ? CMMQS_ReinitializeHardware+0x6a/0x80 [fglrx]
2011-06-28T08:49:44.080457+02:00 localhost kernel: [ 7045.639638]  [<ffffffffa04893e0>] ? firegl_cmmqs_Enable_QS+0x70/0x110 [fglrx]
2011-06-28T08:49:44.080458+02:00 localhost kernel: [ 7045.639657]  [<ffffffffa0488370>] ? firegl_cmmqs_enableqs+0x20/0x90 [fglrx]
2011-06-28T08:49:44.080460+02:00 localhost kernel: [ 7045.639670]  [<ffffffffa0459266>] ? KCL_PosixSecurityCapCheck+0x26/0x30 [fglrx]
2011-06-28T08:49:44.080462+02:00 localhost kernel: [ 7045.639686]  [<ffffffffa0466d98>] ? firegl_ioctl+0x1e8/0x250 [fglrx]
2011-06-28T08:49:44.080463+02:00 localhost kernel: [ 7045.639691]  [<ffffffff8118e342>] ? fsnotify+0x1c2/0x2a0
2011-06-28T08:49:44.080465+02:00 localhost kernel: [ 7045.639704]  [<ffffffffa0457ebe>] ? ip_firegl_unlocked_ioctl+0xe/0x20 [fglrx]
2011-06-28T08:49:44.080467+02:00 localhost kernel: [ 7045.639707]  [<ffffffff81166edf>] ? do_vfs_ioctl+0x8f/0x500
2011-06-28T08:49:44.080468+02:00 localhost kernel: [ 7045.639710]  [<ffffffff81155488>] ? vfs_write+0x148/0x180
2011-06-28T08:49:44.080470+02:00 localhost kernel: [ 7045.639712]  [<ffffffff811673e1>] ? sys_ioctl+0x91/0xa0
2011-06-28T08:49:44.080472+02:00 localhost kernel: [ 7045.639716]  [<ffffffff813f1e02>] ? system_call_fastpath+0x16/0x1b
2011-06-28T08:49:44.080473+02:00 localhost kernel: [ 7045.639719] pubdev:0xffffffffa069f080, num of device:1 , name:fglrx, major 8, minor 86. 
2011-06-28T08:49:44.080475+02:00 localhost kernel: [ 7045.639722] device 0 : 0xffff88022c530000 .
2011-06-28T08:49:44.080476+02:00 localhost kernel: [ 7045.639723] Asic ID:0x68c1, revision:0x28, MMIOReg:0xffffc90011a40000.
2011-06-28T08:49:44.080486+02:00 localhost kernel: [ 7045.639725] FB phys addr: 0xd0000000, MC :0xf00000000, Total FB size :0x40000000.
2011-06-28T08:49:44.080489+02:00 localhost kernel: [ 7045.639727] gart table MC:0xf0f8fd000, Physical:0xdf8fd000, size:0x402000.
2011-06-28T08:49:44.080490+02:00 localhost kernel: [ 7045.639730] mc_node :FB, total 1 zones
2011-06-28T08:49:44.080492+02:00 localhost kernel: [ 7045.639731]     MC start:0xf00000000, Physical:0xd0000000, size:0xfd00000.
2011-06-28T08:49:44.080494+02:00 localhost kernel: [ 7045.639733]     Mapped heap -- Offset:0x0, size:0xf8fd000, reference count:16, mapping count:0,
2011-06-28T08:49:44.080496+02:00 localhost kernel: [ 7045.639735]     Mapped heap -- Offset:0x0, size:0x1000000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080498+02:00 localhost kernel: [ 7045.639737]     Mapped heap -- Offset:0xf8fd000, size:0x403000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080500+02:00 localhost kernel: [ 7045.639739] mc_node :INV_FB, total 1 zones
2011-06-28T08:49:44.080502+02:00 localhost kernel: [ 7045.639740]     MC start:0xf0fd00000, Physical:0xdfd00000, size:0x30300000.
2011-06-28T08:49:44.080504+02:00 localhost kernel: [ 7045.639742]     Mapped heap -- Offset:0x302f4000, size:0xc000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080506+02:00 localhost kernel: [ 7045.639744] mc_node :GART_USWC, total 2 zones
2011-06-28T08:49:44.080508+02:00 localhost kernel: [ 7045.639745]     MC start:0x40100000, Physical:0x0, size:0x50000000.
2011-06-28T08:49:44.080510+02:00 localhost kernel: [ 7045.639747]     Mapped heap -- Offset:0x0, size:0x2000000, reference count:6, mapping count:0,
2011-06-28T08:49:44.080511+02:00 localhost kernel: [ 7045.639748] mc_node :GART_CACHEABLE, total 3 zones
2011-06-28T08:49:44.080513+02:00 localhost kernel: [ 7045.639750]     MC start:0x10400000, Physical:0x0, size:0x2fd00000.
2011-06-28T08:49:44.080514+02:00 localhost kernel: [ 7045.639752]     Mapped heap -- Offset:0x1600000, size:0x700000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080516+02:00 localhost kernel: [ 7045.639753]     Mapped heap -- Offset:0x1d00000, size:0x700000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080518+02:00 localhost kernel: [ 7045.639756]     Mapped heap -- Offset:0x4300000, size:0x800000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080553+02:00 localhost kernel: [ 7045.639758]     Mapped heap -- Offset:0x3500000, size:0xe00000, reference count:2, mapping count:0,
2011-06-28T08:49:44.080556+02:00 localhost kernel: [ 7045.639759]     Mapped heap -- Offset:0xf00000, size:0x700000, reference count:2, mapping count:0,
2011-06-28T08:49:44.080558+02:00 localhost kernel: [ 7045.639761]     Mapped heap -- Offset:0x900000, size:0x600000, reference count:2, mapping count:0,
2011-06-28T08:49:44.080560+02:00 localhost kernel: [ 7045.639763]     Mapped heap -- Offset:0x200000, size:0x700000, reference count:13, mapping count:0,
2011-06-28T08:49:44.080562+02:00 localhost kernel: [ 7045.639765]     Mapped heap -- Offset:0x0, size:0x200000, reference count:8, mapping count:0,
2011-06-28T08:49:44.080566+02:00 localhost kernel: [ 7045.639767]     Mapped heap -- Offset:0xef000, size:0x11000, reference count:1, mapping count:0,
2011-06-28T08:49:44.080576+02:00 localhost kernel: [ 7045.639829] GRBM : 0x3828, SRBM : 0x200000c0 .
2011-06-28T08:49:44.080578+02:00 localhost kernel: [ 7045.639832] CP_RB_BASE : 0x401000, CP_RB_RPTR : 0x20 , CP_RB_WPTR :0x20.
2011-06-28T08:49:44.080580+02:00 localhost kernel: [ 7045.639834] CP_IB1_BUFSZ:0x0, CP_IB1_BASE_HI:0x0, CP_IB1_BASE_LO:0x0.
2011-06-28T08:49:44.080582+02:00 localhost kernel: [ 7045.639836] last submit IB buffer -- MC :0x0. Can't found mapped physical page for this MC .
2011-06-28T08:49:44.080584+02:00 localhost kernel: [ 7045.639838] Dump the trace queue.
2011-06-28T08:49:44.080586+02:00 localhost kernel: [ 7045.639839] End of dump

Since I use fglrx I really wanted to make sure that no other drivers interfere and I created this in /etc/modprobe.d/ (the intel driver is probably the graphics chip integrated in the i5 480m CPU)

 ~ % cat /etc/modprobe.d/graphicdrivers.conf
install radeon /bin/false
install ttm /bin/false
install drm_kms_helper /bin/false
install drm /bin/false
install intel_agp /bin/false
install intel_gtt /bin/false
install intel_ips /bin/false

But as far as I see it has no effect on this behaviour.

AnnS · 2011-06-30 13:41:57

Thank you for your answer!

It's too bad there's not a solution for this. What I think is strange, is the autologin thing. It can't be a coincidence. Everytime I tried to resume with autologin disable, it couldn't. Enabling autologin, it doesn't happen always. But just now it happened again.

About the ASIC entry, as far as I can see, this is the only entries ASIC related:

Jun 29 12:42:11 localhost kernel: [   79.981281] [fglrx] Power down the ASIC .
Jun 29 12:42:11 localhost kernel: [   81.737851] [fglrx] Power up the ASIC
Jun 29 19:30:35 localhost kernel: [ 6245.682000] [fglrx] Power down the ASIC .
Jun 29 19:30:35 localhost kernel: [ 6247.323004] [fglrx] Power up the ASIC
Jun 29 19:37:20 localhost kernel: [ 6646.893319] [fglrx] Power down the ASIC .
Jun 29 19:37:20 localhost kernel: [ 6648.602262] [fglrx] Power up the ASIC
Jun 29 23:32:42 localhost kernel: [   71.790679] [fglrx] Power down the ASIC .
Jun 29 23:32:42 localhost kernel: [   73.933285] [fglrx] Power up the ASIC
Jun 29 23:33:09 localhost kernel: [   93.906339] [fglrx] Power down the ASIC .
Jun 29 23:33:09 localhost kernel: [   95.773695] [fglrx] Power up the ASIC
Jun 29 23:35:10 localhost kernel: [   67.955308] [fglrx] Power down the ASIC .
Jun 29 23:35:10 localhost kernel: [   69.637456] [fglrx] Power up the ASIC
Jun 30 02:05:14 localhost kernel: [ 9043.380102] [fglrx] Power down the ASIC .
Jun 30 02:05:14 localhost kernel: [ 9044.991735] [fglrx] Power up the ASIC

And the las entry I have in suspend-pm, is:

Thu Jun 30 08:51:33 VET 2011: performing suspend

In others, after that it says (for example):

jue jun 30 02:05:03 VET 2011: performing suspend
jue jun 30 02:05:14 VET 2011: Awake.
jue jun 30 02:05:14 VET 2011: Running hooks for resume

I guess it's because this time it didn't wake up.

Anyway, it's too bad, I rely on suspend too much. I kind of had the hope you found a solution for this. But I really appreciate your answer anyway, so thanks again!

By the way, I did what you did in modprobe.d and at least now the radeon driver doesn't get loaded. Though I don't know why it did in the first place.

Update: Ok, I'm still searching and I've found people with a similar problem who solved it with different options. I'm going to try each and see if any works for me.

1) Disable wireless before suspending. Don't ask me why, haha, but it just occurred to me and I have nothing to lose.
2) Adding resume=/dev/disk to the kernel line in /boot/grub/menu.lst.
3) Adding acpi_sleep=nonvs to the kernel boot line.
4) Adding resume and/or remove autodetect in/to the HOOKS array in /etc/mkinitcpio.conf as suggested in the Arch wiki for pm-utils.
5) Disable lock the screen after suspend (I don't know, but if enabling autologin worked, why not this? haha).
6) Install the open source driver again and see if it happens, since I can't remember.

Right now I'm testing option one and so far, it resumes just fine. But I'm not sure that's the solution. I'll test everything I can, there must be a solution for this.

Last edited by AnnS (2011-06-30 18:04:16)

AnnS · 2011-07-01 01:47:55

So far, it's still OK with option 1. It might be a coincidence, though. Also, does anyone know what this mean and if it's important, perhaps it's somehow related?

localhost kernel: [    5.782891] ACPI: Deprecated procfs I/F for battery is loaded, please retry with CONFIG_ACPI_PROCFS_POWER cleared
localhost kernel: [    5.898270] ACPI: Deprecated procfs I/F for AC is loaded, please retry with CONFIG_ACPI_PROCFS_POWER cleared

I found several posts with users posting this, but I haven't found an answer explaining what it is and/or if it's wrong and needs to be corrected.

Cdh · 2011-07-01 13:33:00

AnnS wrote:

Update: Ok, I'm still searching and I've found people with a similar problem who solved it with different options. I'm going to try each and see if any works for me.
1) Disable wireless before suspending. Don't ask me why, haha, but it just occurred to me and I have nothing to lose.

Interesting idea. Since the hotkey doesn't disable the wifi really (just puts the interface down I think) I maybe go with unloading ath9k.

AnnS wrote:

2) Adding resume=/dev/disk to the kernel line in /boot/grub/menu.lst.

I don't think that helps...
I have resume=/dev/disk/by-uuid/... there but I have used uswsusp because I have swap in a file (when it is needed with 8 gb I'm screwed anyway).

AnnS wrote:

3) Adding acpi_sleep=nonvs to the kernel boot line.

Sounds interesting. Will it decrease battery life?

AnnS wrote:

4) Adding resume and/or remove autodetect in/to the HOOKS array in /etc/mkinitcpio.conf as suggested in the Arch wiki for pm-utils.

I have no autodetect there and I have uresume because of uswsusp. It works with s2disk.

AnnS wrote:

5) Disable lock the screen after suspend (I don't know, but if enabling autologin worked, why not this? haha).

I don't have any power manager installed that would do this.

AnnS wrote:

6) Install the open source driver again and see if it happens, since I can't remember.

I thought of doing that too but I won't use the open source driver before this GSOC is over anyway, so I never really did it again...

acpi_sleep=nonvs may help with resuming in my 1) when nopat is not added to the kernel line but I doubt it will help when fglrx produces an "ASIC hang" or when X just hangs...

AnnS · 2011-07-01 15:52:37

Well, the way I was disabling the wireless, was with the hotkey. After 7 suspend, more or so, it gave me the black screen at resume. I might try later as you said, unloading ath9k.

About acpi_sleep=nonvs, I really don't know if it decreases battery life, I haven't read anyone saying that, so I hope not, haha. I'll see when I test it. In my case, from what I saw, there are no "ASIC hang" messages, so I don't know what is causing that for you.
And reading in the Ubuntu forum, I found that for some, editing /etc/pm/config.d/unload_module (I don't have this file but I guess one can just create it) and adding SUSPEND_MODULES="name of wireless driver" did work, so for some, the problem is the wireless.

Right now I'm with option 4, but considering you've already done it, I'm not so positive, haha.
Some one even said that he uses option 3 and 4, so I'll test that too.

What I've seen, is that even though many share the -apparently- same problem, not the same solution works for everyone. So that's why I'm taking all options I can to test.
Another option I'm considering is installing a distro like Ubuntu and see if it happens. If it doesn't and since in Ubuntu most of the things are already configured, it could be a way to see what are we missing. I don't think it happens, since someone I know with the same laptop has that distro and said it didn't.

That will take me time, since I really don't know anything and maybe nothing I try will work, haha, but it's fun to learn. There has to be a solution for this.

Last edited by AnnS (2011-07-01 15:53:08)

Cdh · 2011-07-01 23:54:42

I don't get this ASIC hang often.

This is from my log:

2011-06-19T09:36:47.753952+02:00 localhost kernel: [139645.002785] [fglrx] ASIC hang happened
2011-06-24T12:06:42.528376+02:00 localhost kernel: [91802.374064] [fglrx] ASIC hang happened
2011-06-25T11:08:11.818292+02:00 localhost kernel: [55323.614764] [fglrx] ASIC hang happened
2011-06-28T08:49:44.080395+02:00 localhost kernel: [ 7045.639214] [fglrx] ASIC hang happened

So 1-5 days in between them and I often suspend several times a day.

But I often too get the hanging X with no error message at all.

After a bit of thinking I don't think ath9k should be responsible for a hanging X. I already had a hang with ath9k definitively unloaded...

If you don't know about the sysrq keys you should maybe activate them:
https://wiki.archlinux.org/index.php/Keyboard_Shortcuts
It's not good doing a reboot this way but still better than an unclean filesystem.

Is there a way you can retrieve process statistics when the black screen happens? Can you run a ssh server and get "ps aux" to confirm my behaviour that X seems to be running normally but consuming 100% cpu?

Some thoughts crossed my mind:
Do you use banshee or dropbox?

Last edited by Cdh (2011-07-01 23:58:41)

AnnS · 2011-07-02 00:35:44

Hey! About the Sysrq key, I already activate them, but unfortunately it's not working for me. When I resume and get the black screen, I've tried pressing Alt+Sysrq and Fn+Alt+Sysrq, even Alt Gr+Sysrq, but it still doesn't work. Maybe I'm doing it wrong or the system just doesn't respond.

And I wish I could run a SSH server and do that, but I don't know anything about that! Total newbie here, haha. I'll be reading about that and see if I can try.
What I can say, is that when I resume, the disk seems to run/load for about 3 seconds, then stops and the fans keep running at high speed. No disk activity.

And about banshee and dropbox, I don't use either.

By the way, option 4 alone definitely didn't work. I'm now with both option 4 and option 5. So far, still OK.

Oh, and can you tell me if you have the "Deprecated procfs I/F (and AC) for battery is loaded, please retry with CONFIG_ACPI_PROCFS_POWER cleared", or is that just me?
Thanks!

Update: Option 4 and 5 together did not work. But I was thinking... I don't have Acpid installed, so perhaps that's why?

Also, this might be a coincidence, but every once in a while at boot, when I log to KDE, I get no sound. I have to either reboot or just close the session to get sound again. And I think that when that happens, I can't resume. A few minutes ago when I logged in and noticed I didn't have sound, I suspended and yes, couldn't resume. It might be a coincidence, though. Also, in pm-suspend.log, this is always there:

Running hook /etc/pm/sleep.d/90alsa resume suspend:
ALSA lib pulse.c:229:(pulse_connect) PulseAudio: Unable to connect: Connection refused

aplay: main:660: audio open error: Connection refused

And just out of curiosity, do you have Acpid, Hal and/or laptop-mode-tools installed? I don't.

Last edited by AnnS (2011-07-02 04:25:17)

Cdh · 2011-07-02 08:24:17

It's alt+"prnt screen" (or whatever that key is called in english)+one of the letters.
So pressing alt+prntscrn+r, releasing the keys, pressing alt+prntscrn+e, etc. should make a more or less clean reboot.

ssh is easy: Just installing openssh and putting sshd in /etc/rc.conf DAEMONS. And putting "sshd: ALL: ALLOW" in /etc/hosts.allow (or remove "ALL: ALL: DENY" from /etc/hosts.deny). Then you can login from any computer with "ssh username@ip"

hm... yes:

2011-07-01T16:25:24.003538+02:00 localhost kernel: [   26.775260] thermal LNXTHERM:00: registered as thermal_zone0
2011-07-01T16:25:24.003539+02:00 localhost kernel: [   26.775264] ACPI: Thermal Zone [TZS0] (44 C)
2011-07-01T16:25:24.003540+02:00 localhost kernel: [   26.787275] ACPI: Deprecated procfs I/F for battery is loaded, please retry with CONFIG_ACPI_PROCFS_POWER cleared
2011-07-01T16:25:24.003541+02:00 localhost kernel: [   26.787281] ACPI: Battery Slot [BAT0] (battery present)
2011-07-01T16:25:24.003542+02:00 localhost kernel: [   26.790816] ACPI: Deprecated procfs I/F for AC is loaded, please retry with CONFIG_ACPI_PROCFS_POWER cleared
2011-07-01T16:25:24.003543+02:00 localhost kernel: [   26.790991] ACPI: AC Adapter [ADP1] (off-line)
2011-07-01T16:25:24.003545+02:00 localhost kernel: [   26.800617] thermal LNXTHERM:01: registered as thermal_zone1
2011-07-01T16:25:24.003546+02:00 localhost kernel: [   26.800621] ACPI: Thermal Zone [TZS1] (32 C)

But I don't think it's directly related to the problem.

ACPI stuff is installed but I have no service running.
laptop-mode-tools is installed too but that isn't running either.
hal is not installed.

I use systemd and am still trying to get used to it...

AnnS · 2011-07-02 22:22:28

Hey!

It's alt+"prnt screen" (or whatever that key is called in english)+one of the letters.
So pressing alt+prntscrn+r, releasing the keys, pressing alt+prntscrn+e, etc. should make a more or less clean reboot.

I've tried that. "Alt + PrtSc/SysRq + R", release the keys, "Alt + PrtSc/SysRq + E", release the keys, "Alt + PrtSc/SysRq + I" and so on. But it still didn't work. I waited like three seconds between each key, I think I was doing it right, but nothing. I read in your thread this:

This time again the laptop didn't respond to anything. No visible hard disk activity at all, sysrq didn't work

So maybe that's the problem I have. I added 'nopat' to the kernel line as you did, maybe the Sysrq key will work when it happens again.

Also, I've been reading about OpenSSH and understanding it and I was able to connect from my other laptop. So I'll make sure to do that when the black screen happens and run "ps aux". I don't know if it will work, though, since when I resume (and get the black screen), the wifi light doesn't turn on, so maybe it doesn't 'resume'. But I'll try anyway and post the output.

Update: Oh, I just remembered something. Sometimes I don't boot to the GUI but to the CLI, and I've tried suspending/resuming from there. Suspend does work, but sometimes I get the black screen at resume. Well, when that happens there (not in the GUI), the wifi light does turn on and the keyboard works as well. I'm able to type [blindly, the screen is still black or blank] Alt + F2 or F3, etc (just to make sure, haha), log as root, type the password and then 'reboot' and the laptop does reboot.

Some links:
Also, there's some interesting information here: https://bbs.archlinux.org/viewtopic.php?id=114198
The last user added acpi_sleep=s3_bios. I don't know if I need Acpid for that or the acpi_sleep=nonvs to work, though.
And here, some users mention the resume=/dev line: https://bbs.archlinux.org/viewtopic.php?id=80702&p=2
And also here: https://bbs.archlinux.org/viewtopic.php?id=87306

Cheers!

Last edited by AnnS (2011-07-03 00:46:37)

AnnS · 2011-07-09 01:04:42

Hey! I just wanted to let you know that since my last post, I haven't had problems resuming after suspend. I don't know if it's fixed, and if it is, what fixed it. Perhaps an update? Though I don't remember seeing anything that could be related, but who knows, we don't even know what's the cause.

What I've done, until my last post, is:

1.- Adding 'resume' to the hooks array in /etc/mkinitcpio.conf. And also removing 'autodetect'.
2.- Adding 'nopat' to the kernel line, as you did.
3.- I doubt this is related, but it's the last thing I did: Disabling IPv6, because I don't need it.

And that's it. Maybe it's not fixed, but so far, it resumes fine and I've tried many times in the past days.

Cheers!

Cdh · 2011-07-09 06:50:22

Actually I didn't have black screens for many days either...
Must have been some update that "fixed" it. But I'm not convinced it won't reappear in the future...

AnnS · 2011-07-15 05:01:00

After so many days without the problem, I got the black screen of death minutes ago. I suspended and about 40 minutes later tried to resume and got the black screen. At least this time the wifi led was on and the disk seemed more active than other times. Though unfortunately I couldn't ssh into the laptop since I didn't have sshd enabled... But I could use the SysRq key, and for the first time, I got the "ASIC hang happened". I'm thinking that this might have something to do with the "nopat" line. Since before I added this line, the wifi led was off, there was no disk activity (past the first 3-4 seconds when trying to resume) and the SysRq key wouldn't work, so I had to hard shutdown. So at least now the SysRq key works, that's something.

This is the full log from the failed resume:

Jul 14 23:46:20 localhost kernel: [ 8505.476150] PM: Syncing filesystems ... done.
Jul 14 23:46:20 localhost kernel: [ 8505.477733] PM: Preparing system for mem sleep
Jul 14 23:46:20 localhost kernel: [ 8505.477749] Freezing user space processes ... (elapsed 0.01 seconds) done.
Jul 14 23:46:20 localhost kernel: [ 8505.488608] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Jul 14 23:46:20 localhost kernel: [ 8505.501902] PM: Entering mem sleep
Jul 14 23:46:20 localhost kernel: [ 8505.502003] Suspending console(s) (use no_console_suspend to debug)
Jul 14 23:46:20 localhost kernel: [ 8505.551975] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jul 14 23:46:20 localhost kernel: [ 8505.629234] HDA Intel 0000:02:00.1: PCI INT B disabled
Jul 14 23:46:20 localhost kernel: [ 8505.629293] ACPI handle has no context!
Jul 14 23:46:20 localhost kernel: [ 8505.629299] ehci_hcd 0000:00:1d.0: PCI INT A disabled
Jul 14 23:46:20 localhost kernel: [ 8505.629321] [fglrx] IRQ 44 Disabled
Jul 14 23:46:20 localhost kernel: [ 8505.629468] [fglrx] Preparing suspend fglrx in kernel.
Jul 14 23:46:20 localhost kernel: [ 8505.629964] ehci_hcd 0000:00:1a.0: PCI INT A disabled
Jul 14 23:46:20 localhost kernel: [ 8505.637746] kworker/u:29: page allocation failure. order:10, mode:0x4020
Jul 14 23:46:20 localhost kernel: [ 8505.637749] Pid: 8403, comm: kworker/u:29 Tainted: P            2.6.39-ARCH #1
Jul 14 23:46:20 localhost kernel: [ 8505.637751] Call Trace:
Jul 14 23:46:20 localhost kernel: [ 8505.637759]  [<ffffffff810fc30f>] __alloc_pages_nodemask+0x78f/0x8c0
Jul 14 23:46:20 localhost kernel: [ 8505.637762]  [<ffffffff81132673>] alloc_pages_current+0xa3/0x110
Jul 14 23:46:20 localhost kernel: [ 8505.637766]  [<ffffffff810f821e>] __get_free_pages+0xe/0x50
Jul 14 23:46:20 localhost kernel: [ 8505.637769]  [<ffffffff8113d81f>] kmalloc_order_trace+0x3f/0x100
Jul 14 23:46:20 localhost kernel: [ 8505.637805]  [<ffffffffa06c1cf3>] ? __start_CPDMA_copy_evergreen+0xa3/0xc0 [fglrx]
Jul 14 23:46:20 localhost kernel: [ 8505.637808]  [<ffffffff8113da77>] __kmalloc+0x197/0x1a0
Jul 14 23:46:20 localhost kernel: [ 8505.637820]  [<ffffffffa069438e>] KCL_MEM_SmallBufferAllocAtomic+0x1e/0x20 [fglrx]
Jul 14 23:46:20 localhost kernel: [ 8505.637836]  [<ffffffffa06a7da3>] firegl_save_fb+0x233/0x360 [fglrx]
Jul 14 23:46:20 localhost kernel: [ 8505.637853]  [<ffffffffa06a6f35>] ? firegl_pm_save_framebuffer+0x1f5/0x280 [fglrx]
Jul 14 23:46:20 localhost kernel: [ 8505.637869]  [<ffffffffa06a9b2d>] ? firegl_cail_powerdown+0x8d/0x200 [fglrx]
Jul 14 23:46:20 localhost kernel: [ 8505.637879]  [<ffffffffa0691fc7>] ? fglrx_pci_suspend+0x87/0x150 [fglrx]
Jul 14 23:46:20 localhost kernel: [ 8505.637884]  [<ffffffff812390f6>] ? pci_legacy_suspend+0x46/0xe0
Jul 14 23:46:20 localhost kernel: [ 8505.637886]  [<ffffffff8123a3ad>] ? pci_pm_suspend+0xdd/0x130
Jul 14 23:46:20 localhost kernel: [ 8505.637890]  [<ffffffff812e5b35>] ? pm_op+0x175/0x1c0
Jul 14 23:46:20 localhost kernel: [ 8505.637892]  [<ffffffff812e5c32>] ? __device_suspend+0xb2/0x1a0
Jul 14 23:46:20 localhost kernel: [ 8505.637896]  [<ffffffff81085b50>] ? async_schedule+0x20/0x20
Jul 14 23:46:20 localhost kernel: [ 8505.637898]  [<ffffffff812e5d41>] ? async_suspend+0x21/0x50
Jul 14 23:46:20 localhost kernel: [ 8505.637900]  [<ffffffff81085bcf>] ? async_run_entry_fn+0x7f/0x180
Jul 14 23:46:20 localhost kernel: [ 8505.637903]  [<ffffffff81077f6e>] ? process_one_work+0x11e/0x4c0
Jul 14 23:46:20 localhost kernel: [ 8505.637905]  [<ffffffff810788ff>] ? worker_thread+0x15f/0x350
Jul 14 23:46:20 localhost kernel: [ 8505.637907]  [<ffffffff810787a0>] ? manage_workers.isra.29+0x230/0x230
Jul 14 23:46:20 localhost kernel: [ 8505.637909]  [<ffffffff8107d6ec>] ? kthread+0x8c/0xa0
Jul 14 23:46:20 localhost kernel: [ 8505.637912]  [<ffffffff813e9fe4>] ? kernel_thread_helper+0x4/0x10
Jul 14 23:46:20 localhost kernel: [ 8505.637914]  [<ffffffff8107d660>] ? kthread_worker_fn+0x190/0x190
Jul 14 23:46:20 localhost kernel: [ 8505.637915]  [<ffffffff813e9fe0>] ? gs_change+0x13/0x13
Jul 14 23:46:20 localhost kernel: [ 8505.637917] Mem-Info:
Jul 14 23:46:20 localhost kernel: [ 8505.637918] Node 0 DMA per-cpu:
Jul 14 23:46:20 localhost kernel: [ 8505.637919] CPU    0: hi:    0, btch:   1 usd:   0
Jul 14 23:46:20 localhost kernel: [ 8505.637921] CPU    1: hi:    0, btch:   1 usd:   0
Jul 14 23:46:20 localhost kernel: [ 8505.637922] CPU    2: hi:    0, btch:   1 usd:   0
Jul 14 23:46:20 localhost kernel: [ 8505.637923] CPU    3: hi:    0, btch:   1 usd:   0
Jul 14 23:46:20 localhost kernel: [ 8505.637924] Node 0 DMA32 per-cpu:
Jul 14 23:46:20 localhost kernel: [ 8505.637925] CPU    0: hi:  186, btch:  31 usd:  18
Jul 14 23:46:20 localhost kernel: [ 8505.637927] CPU    1: hi:  186, btch:  31 usd: 185
Jul 14 23:46:20 localhost kernel: [ 8505.637928] CPU    2: hi:  186, btch:  31 usd:   1
Jul 14 23:46:20 localhost kernel: [ 8505.637929] CPU    3: hi:  186, btch:  31 usd:  45
Jul 14 23:46:20 localhost kernel: [ 8505.637930] Node 0 Normal per-cpu:
Jul 14 23:46:20 localhost kernel: [ 8505.637931] CPU    0: hi:  186, btch:  31 usd: 185
Jul 14 23:46:20 localhost kernel: [ 8505.637932] CPU    1: hi:  186, btch:  31 usd: 158
Jul 14 23:46:20 localhost kernel: [ 8505.637934] CPU    2: hi:  186, btch:  31 usd: 151
Jul 14 23:46:20 localhost kernel: [ 8505.637935] CPU    3: hi:  186, btch:  31 usd:  44
Jul 14 23:46:20 localhost kernel: [ 8505.637938] active_anon:264255 inactive_anon:50599 isolated_anon:0
Jul 14 23:46:20 localhost kernel: [ 8505.637939]  active_file:81821 inactive_file:445828 isolated_file:0
Jul 14 23:46:20 localhost kernel: [ 8505.637939]  unevictable:0 dirty:11 writeback:0 unstable:0
Jul 14 23:46:20 localhost kernel: [ 8505.637940]  free:33258 slab_reclaimable:12432 slab_unreclaimable:5104
Jul 14 23:46:20 localhost kernel: [ 8505.637941]  mapped:66993 shmem:1158 pagetables:7761 bounce:0
Jul 14 23:46:20 localhost kernel: [ 8505.637942] Node 0 DMA free:15780kB min:264kB low:328kB high:396kB active_anon:0kB inactive_anon:64kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15676kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:8kB kernel_stack:0kB pagetables:48kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
Jul 14 23:46:20 localhost kernel: [ 8505.637948] lowmem_reserve[]: 0 2989 3872 3872
Jul 14 23:46:20 localhost kernel: [ 8505.637950] Node 0 DMA32 free:98084kB min:51956kB low:64944kB high:77932kB active_anon:912996kB inactive_anon:57860kB active_file:243344kB inactive_file:1551960kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:3060784kB mlocked:0kB dirty:24kB writeback:0kB mapped:64464kB shmem:3904kB slab_reclaimable:30264kB slab_unreclaimable:2868kB kernel_stack:632kB pagetables:9772kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
Jul 14 23:46:20 localhost kernel: [ 8505.637956] lowmem_reserve[]: 0 0 883 883
Jul 14 23:46:20 localhost kernel: [ 8505.637958] Node 0 Normal free:19168kB min:15360kB low:19200kB high:23040kB active_anon:144024kB inactive_anon:144472kB active_file:83940kB inactive_file:231352kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:904960kB mlocked:0kB dirty:20kB writeback:0kB mapped:203508kB shmem:728kB slab_reclaimable:19464kB slab_unreclaimable:17540kB kernel_stack:2064kB pagetables:21224kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
Jul 14 23:46:20 localhost kernel: [ 8505.637964] lowmem_reserve[]: 0 0 0 0
Jul 14 23:46:20 localhost kernel: [ 8505.637965] Node 0 DMA: 1*4kB 0*8kB 0*16kB 1*32kB 2*64kB 2*128kB 2*256kB 1*512kB 2*1024kB 2*2048kB 2*4096kB = 15780kB
Jul 14 23:46:20 localhost kernel: [ 8505.637970] Node 0 DMA32: 3368*4kB 1866*8kB 1033*16kB 584*32kB 119*64kB 45*128kB 20*256kB 9*512kB 9*1024kB 1*2048kB 0*4096kB = 97984kB
Jul 14 23:46:20 localhost kernel: [ 8505.637974] Node 0 Normal: 88*4kB 179*8kB 357*16kB 133*32kB 32*64kB 4*128kB 3*256kB 4*512kB 0*1024kB 1*2048kB 0*4096kB = 19176kB
Jul 14 23:46:20 localhost kernel: [ 8505.637978] 528820 total pagecache pages
Jul 14 23:46:20 localhost kernel: [ 8505.637979] 0 pages in swap cache
Jul 14 23:46:20 localhost kernel: [ 8505.637981] Swap cache stats: add 8, delete 8, find 0/0
Jul 14 23:46:20 localhost kernel: [ 8505.637982] Free swap  = 4000116kB
Jul 14 23:46:20 localhost kernel: [ 8505.637982] Total swap = 4000148kB
Jul 14 23:46:20 localhost kernel: [ 8505.648067] 1015792 pages RAM
Jul 14 23:46:20 localhost kernel: [ 8505.648069] 95954 pages reserved
Jul 14 23:46:20 localhost kernel: [ 8505.648069] 452085 pages shared
Jul 14 23:46:20 localhost kernel: [ 8505.648070] 604505 pages non-shared
Jul 14 23:46:20 localhost kernel: [ 8505.648107] sd 0:0:0:0: [sda] Stopping disk
Jul 14 23:46:20 localhost kernel: [ 8505.731612] HDA Intel 0000:00:1b.0: PCI INT A disabled
Jul 14 23:46:20 localhost kernel: [ 8505.893374] [fglrx] Suspending fglrx in kernel completed.
Jul 14 23:46:20 localhost kernel: [ 8505.893377] [fglrx] Power down the ASIC .
Jul 14 23:46:20 localhost kernel: [ 8506.356479] PM: suspend of devices complete after 843.272 msecs
Jul 14 23:46:20 localhost kernel: [ 8506.409696] PM: late suspend of devices complete after 53.343 msecs
Jul 14 23:46:20 localhost kernel: [ 8506.409993] ACPI: Preparing to enter system sleep state S3
Jul 14 23:46:20 localhost kernel: [ 8506.526263] PM: Saving platform NVS memory
Jul 14 23:46:20 localhost kernel: [ 8506.528060] Disabling non-boot CPUs ...
Jul 14 23:46:20 localhost kernel: [ 8506.672249] CPU 1 is now offline
Jul 14 23:46:20 localhost kernel: [ 8506.805254] CPU 2 is now offline
Jul 14 23:46:20 localhost kernel: [ 8506.931586] CPU 3 is now offline
Jul 14 23:46:20 localhost kernel: [ 8506.931924] Extended CMOS year: 2000
Jul 14 23:46:20 localhost kernel: [ 8506.932121] ACPI: Low-level resume complete
Jul 14 23:46:20 localhost kernel: [ 8506.932167] PM: Restoring platform NVS memory
Jul 14 23:46:20 localhost kernel: [ 8506.932608] Extended CMOS year: 2000
Jul 14 23:46:20 localhost kernel: [ 8506.932626] Enabling non-boot CPUs ...
Jul 14 23:46:20 localhost kernel: [ 8506.939427] Booting Node 0 Processor 1 APIC 0x4
Jul 14 23:46:20 localhost kernel: [ 8506.939430] smpboot cpu 1: start_ip = 97000
Jul 14 23:46:20 localhost kernel: [ 8507.055234] Switched to NOHz mode on CPU #1
Jul 14 23:46:20 localhost kernel: [ 8507.095325] NMI watchdog enabled, takes one hw-pmu counter.
Jul 14 23:46:20 localhost kernel: [ 8507.095442] CPU1 is up
Jul 14 23:46:20 localhost kernel: [ 8507.095504] Booting Node 0 Processor 2 APIC 0x1
Jul 14 23:46:20 localhost kernel: [ 8507.095505] smpboot cpu 2: start_ip = 97000
Jul 14 23:46:20 localhost kernel: [ 8507.211565] Switched to NOHz mode on CPU #2
Jul 14 23:46:20 localhost kernel: [ 8507.251738] NMI watchdog enabled, takes one hw-pmu counter.
Jul 14 23:46:20 localhost kernel: [ 8507.251873] CPU2 is up
Jul 14 23:46:20 localhost kernel: [ 8507.251962] Booting Node 0 Processor 3 APIC 0x5
Jul 14 23:46:20 localhost kernel: [ 8507.251964] smpboot cpu 3: start_ip = 97000
Jul 14 23:46:20 localhost kernel: [ 8507.367843] Switched to NOHz mode on CPU #3
Jul 14 23:46:20 localhost kernel: [ 8507.408173] NMI watchdog enabled, takes one hw-pmu counter.
Jul 14 23:46:20 localhost kernel: [ 8507.408519] CPU3 is up
Jul 14 23:46:20 localhost kernel: [ 8507.410477] ACPI: Waking up from system sleep state S3
Jul 14 23:46:20 localhost kernel: [ 8507.484393] power_supply BAT0: parent PNP0C0A:00 should not be sleeping
Jul 14 23:46:20 localhost kernel: [ 8507.597538] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0x80100, writing 0x8010b)
Jul 14 23:46:20 localhost kernel: [ 8507.597543] pcieport 0000:00:01.0: restoring config space at offset 0x7 (was 0x2020, writing 0x20002020)
Jul 14 23:46:20 localhost kernel: [ 8507.597547] pcieport 0000:00:01.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010)
Jul 14 23:46:20 localhost kernel: [ 8507.597550] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507)
Jul 14 23:46:20 localhost kernel: [ 8507.597595] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Jul 14 23:46:20 localhost kernel: [ 8507.597608] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf0606000)
Jul 14 23:46:20 localhost kernel: [ 8507.597613] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Jul 14 23:46:20 localhost kernel: [ 8507.597635] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x107)
Jul 14 23:46:20 localhost kernel: [ 8507.597646] HDA Intel 0000:00:1b.0: restoring config space at offset 0x4 (was 0xdc000004, writing 0xf0600004)
Jul 14 23:46:20 localhost kernel: [ 8507.597650] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Jul 14 23:46:20 localhost kernel: [ 8507.597654] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100102)
Jul 14 23:46:20 localhost kernel: [ 8507.597673] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x100, writing 0x4010a)
Jul 14 23:46:20 localhost kernel: [ 8507.597680] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0xf011f001)
Jul 14 23:46:20 localhost kernel: [ 8507.597683] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0xf020f020)
Jul 14 23:46:20 localhost kernel: [ 8507.597687] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x0, writing 0x3030)
Jul 14 23:46:20 localhost kernel: [ 8507.597690] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0x40300)
Jul 14 23:46:20 localhost kernel: [ 8507.597694] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010)
Jul 14 23:46:20 localhost kernel: [ 8507.597698] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Jul 14 23:46:20 localhost kernel: [ 8507.597721] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x200, writing 0x4020b)
Jul 14 23:46:20 localhost kernel: [ 8507.597728] pcieport 0000:00:1c.1: restoring config space at offset 0x9 (was 0x10001, writing 0xc011c001)
Jul 14 23:46:20 localhost kernel: [ 8507.597731] pcieport 0000:00:1c.1: restoring config space at offset 0x8 (was 0x0, writing 0xf030f030)
Jul 14 23:46:20 localhost kernel: [ 8507.597734] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x0, writing 0x4040)
Jul 14 23:46:20 localhost kernel: [ 8507.597737] pcieport 0000:00:1c.1: restoring config space at offset 0x6 (was 0x0, writing 0x50500)
Jul 14 23:46:20 localhost kernel: [ 8507.597742] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810010)
Jul 14 23:46:20 localhost kernel: [ 8507.597746] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Jul 14 23:46:20 localhost kernel: [ 8507.597768] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Jul 14 23:46:20 localhost kernel: [ 8507.597781] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf0607000)
Jul 14 23:46:20 localhost kernel: [ 8507.597786] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
Jul 14 23:46:20 localhost kernel: [ 8507.597803] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x40000, writing 0x400ff)
Jul 14 23:46:20 localhost kernel: [ 8507.597810] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1)
Jul 14 23:46:20 localhost kernel: [ 8507.597813] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x0, writing 0xfff0)
Jul 14 23:46:20 localhost kernel: [ 8507.597816] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x22800000, writing 0x228000f0)
Jul 14 23:46:20 localhost kernel: [ 8507.597823] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Jul 14 23:46:20 localhost kernel: [ 8507.597868] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x205)
Jul 14 23:46:20 localhost kernel: [ 8507.597880] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Jul 14 23:46:20 localhost kernel: [ 8507.597921] pci 0000:00:1f.6: restoring config space at offset 0xf (was 0x300, writing 0x30a)
Jul 14 23:46:20 localhost kernel: [ 8507.597936] pci 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Jul 14 23:46:20 localhost kernel: [ 8507.597958] fglrx_pci 0000:02:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x10b)
Jul 14 23:46:20 localhost kernel: [ 8507.597967] fglrx_pci 0000:02:00.0: restoring config space at offset 0x8 (was 0x1, writing 0x2001)
Jul 14 23:46:20 localhost kernel: [ 8507.597971] fglrx_pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x4, writing 0xcfee0004)
Jul 14 23:46:20 localhost kernel: [ 8507.597975] fglrx_pci 0000:02:00.0: restoring config space at offset 0x4 (was 0xc, writing 0xd000000c)
Jul 14 23:46:20 localhost kernel: [ 8507.597978] fglrx_pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800010)
Jul 14 23:46:20 localhost kernel: [ 8507.597982] fglrx_pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100503)
Jul 14 23:46:20 localhost kernel: [ 8507.598013] HDA Intel 0000:02:00.1: restoring config space at offset 0xf (was 0x2ff, writing 0x20a)
Jul 14 23:46:20 localhost kernel: [ 8507.598024] HDA Intel 0000:02:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xcfedc004)
Jul 14 23:46:20 localhost kernel: [ 8507.598028] HDA Intel 0000:02:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x800010)
Jul 14 23:46:20 localhost kernel: [ 8507.598032] HDA Intel 0000:02:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100103)
Jul 14 23:46:20 localhost kernel: [ 8507.598116] tg3 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Jul 14 23:46:20 localhost kernel: [ 8507.598121] tg3 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100506)
Jul 14 23:46:20 localhost kernel: [ 8507.598178] ath9k 0000:05:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x10a)
Jul 14 23:46:20 localhost kernel: [ 8507.598196] ath9k 0000:05:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xf0300004)
Jul 14 23:46:20 localhost kernel: [ 8507.598200] ath9k 0000:05:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Jul 14 23:46:20 localhost kernel: [ 8507.598205] ath9k 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Jul 14 23:46:20 localhost kernel: [ 8507.598413] PM: early resume of devices complete after 0.957 msecs
Jul 14 23:46:20 localhost kernel: [ 8507.598692] ehci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Jul 14 23:46:20 localhost kernel: [ 8507.598700] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Jul 14 23:46:20 localhost kernel: [ 8507.598705] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.598708] ehci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Jul 14 23:46:20 localhost kernel: [ 8507.598711] HDA Intel 0000:00:1b.0: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.598715] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.598746] pci 0000:00:1e.0: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.598753] HDA Intel 0000:00:1b.0: irq 42 for MSI/MSI-X
Jul 14 23:46:20 localhost kernel: [ 8507.598765] [fglrx] Kernel supports PAT but it has been disabled
Jul 14 23:46:20 localhost kernel: [ 8507.598766] [fglrx] Using driver built-in PAT support instead
Jul 14 23:46:20 localhost kernel: [ 8507.598768] ahci 0000:00:1f.2: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.599319] tg3 0000:03:00.0: wake-up capability disabled by ACPI
Jul 14 23:46:20 localhost kernel: [ 8507.599360] tg3 0000:03:00.0: PME# disabled
Jul 14 23:46:20 localhost kernel: [ 8507.599505] HDA Intel 0000:02:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Jul 14 23:46:20 localhost kernel: [ 8507.599515] HDA Intel 0000:02:00.1: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.599554] HDA Intel 0000:02:00.1: irq 43 for MSI/MSI-X
Jul 14 23:46:20 localhost kernel: [ 8507.599630] sd 0:0:0:0: [sda] Starting disk
Jul 14 23:46:20 localhost kernel: [ 8507.599912] fglrx_pci 0000:02:00.0: setting latency timer to 64
Jul 14 23:46:20 localhost kernel: [ 8507.605625] [fglrx] Power up the ASIC
Jul 14 23:46:20 localhost kernel: [ 8507.605752] [fglrx] Preparing resume fglrx in kernel.
Jul 14 23:46:20 localhost kernel: [ 8507.673839] [fglrx] Resuming fglrx in kernel completed.
Jul 14 23:46:20 localhost kernel: [ 8507.674036] [fglrx] IRQ 44 Enabled
Jul 14 23:46:20 localhost kernel: [ 8507.850159] usb 2-1.5: reset high speed USB device number 3 using ehci_hcd
Jul 14 23:46:20 localhost kernel: [ 8507.916491] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Jul 14 23:46:20 localhost kernel: [ 8507.919985] ata5.00: configured for UDMA/100
Jul 14 23:46:20 localhost kernel: [ 8510.054456] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jul 14 23:46:20 localhost kernel: [ 8510.258470] ata1.00: configured for UDMA/133
Jul 14 23:46:20 localhost kernel: [ 8510.336104] PM: resume of devices complete after 2744.342 msecs
Jul 14 23:46:20 localhost kernel: [ 8510.336358] PM: Finishing wakeup.
Jul 14 23:46:20 localhost kernel: [ 8510.336359] Restarting tasks ... done.
Jul 14 23:46:20 localhost kernel: [ 8510.344265] video LNXVIDEO:00: Restoring backlight state
Jul 14 23:46:21 localhost kernel: [ 8511.289064] tg3 0000:03:00.0: eth0: Link is down
Jul 14 23:46:23 localhost kernel: [ 8513.177532] EXT4-fs (sda5): re-mounted. Opts: commit=0
Jul 14 23:46:24 localhost kernel: [ 8513.797702] EXT4-fs (sda6): re-mounted. Opts: commit=0
Jul 14 23:46:24 localhost kernel: [ 8514.034086] tg3 0000:03:00.0: PME# enabled
Jul 14 23:46:24 localhost kernel: [ 8514.034101] tg3 0000:03:00.0: wake-up capability enabled by ACPI
Jul 14 23:46:24 localhost kernel: [ 8514.057819] tg3 0000:03:00.0: irq 45 for MSI/MSI-X
Jul 14 23:46:24 localhost kernel: [ 8514.291538] tg3 0000:03:00.0: eth0: Link is down
Jul 14 23:46:26 localhost kernel: [ 8516.531715] wlan0: authenticate with 00:1b:11:a3:7b:24 (try 1)
Jul 14 23:46:26 localhost kernel: [ 8516.533596] wlan0: authenticated
Jul 14 23:46:26 localhost kernel: [ 8516.533615] wlan0: associate with 00:1b:11:a3:7b:24 (try 1)
Jul 14 23:46:26 localhost kernel: [ 8516.536019] wlan0: RX AssocResp from 00:1b:11:a3:7b:24 (capab=0x431 status=0 aid=3)
Jul 14 23:46:26 localhost kernel: [ 8516.536021] wlan0: associated
Jul 14 23:49:21 localhost kernel: [ 8690.305910] [fglrx] ASIC hang happened
Jul 14 23:49:21 localhost kernel: [ 8690.305915] Pid: 820, comm: X Tainted: P            2.6.39-ARCH #1
Jul 14 23:49:21 localhost kernel: [ 8690.305917] Call Trace:
Jul 14 23:49:21 localhost kernel: [ 8690.305955]  [<ffffffffa0698d5e>] KCL_DEBUG_OsDump+0xe/0x10 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.305972]  [<ffffffffa06a618c>] firegl_hardwareHangRecovery+0x1c/0x50 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306004]  [<ffffffffa072d419>] ? _ZN4Asic9WaitUntil15ResetASICIfHungEv+0x9/0x10 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306034]  [<ffffffffa072d3cc>] ? _ZN4Asic9WaitUntil15WaitForCompleteEv+0x6c/0xb0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306050]  [<ffffffffa0779d7f>] ? _ZN13BaseUVDEngineI18mmEngineRS780_UVD2E4idleEv+0x7f/0xc0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306081]  [<ffffffffa072c55c>] ? _ZN15ExecutableUnits35flush_all_and_invalidate_HDP_cachesE12_QS_CP_RING_+0xbc/0xe0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306112]  [<ffffffffa072c355>] ? _ZN15ExecutableUnits10CPRingIdleE15idle_WaitMethod12_QS_CP_RING_+0x105/0x1a0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306143]  [<ffffffffa0736807>] ? _ZN11AsicCypress21initializeMicroEngineEv+0x147/0x160 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306158]  [<ffffffffa077a026>] ? _ZN13BaseUVDEngineI18mmEngineRS780_UVD2E14SwitchEngineHWEN8mmEngine15MM_ENGINE_STATEE+0x26/0x1d0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306190]  [<ffffffffa072733a>] ? _ZN18mmEnginesContainer13assertEnginesEb+0x5a/0xa0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306220]  [<ffffffffa072be78>] ? _ZN15ExecutableUnits17assertPrimaryRingEb+0x1f8/0x210 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306251]  [<ffffffffa073372a>] ? _ZN8AsicR6009assertPM4Eb+0x4a/0x70 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306270]  [<ffffffffa06c3350>] ? firegl_cmmqs_disabledriver+0xf0/0xf0 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306299]  [<ffffffffa07080aa>] ? CMMQS_ReinitializeHardware+0x6a/0x80 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306318]  [<ffffffffa06c43e0>] ? firegl_cmmqs_Enable_QS+0x70/0x110 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306337]  [<ffffffffa06c3370>] ? firegl_cmmqs_enableqs+0x20/0x90 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306349]  [<ffffffffa0694256>] ? KCL_PosixSecurityCapCheck+0x26/0x30 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306365]  [<ffffffffa06a1d98>] ? firegl_ioctl+0x1e8/0x250 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306369]  [<ffffffff8118ae62>] ? fsnotify+0x1c2/0x2a0
Jul 14 23:49:21 localhost kernel: [ 8690.306380]  [<ffffffffa0692eae>] ? ip_firegl_unlocked_ioctl+0xe/0x20 [fglrx]
Jul 14 23:49:21 localhost kernel: [ 8690.306385]  [<ffffffff81163b6f>] ? do_vfs_ioctl+0x8f/0x500
Jul 14 23:49:21 localhost kernel: [ 8690.306389]  [<ffffffff81151f48>] ? vfs_write+0x148/0x180
Jul 14 23:49:21 localhost kernel: [ 8690.306391]  [<ffffffff81164071>] ? sys_ioctl+0x91/0xa0
Jul 14 23:49:21 localhost kernel: [ 8690.306396]  [<ffffffff813e8ec2>] ? system_call_fastpath+0x16/0x1b
Jul 14 23:49:21 localhost kernel: [ 8690.306399] pubdev:0xffffffffa08da080, num of device:1 , name:fglrx, major 8, minor 86. 
Jul 14 23:49:21 localhost kernel: [ 8690.306402] device 0 : 0xffff88012c40c000 .
Jul 14 23:49:21 localhost kernel: [ 8690.306403] Asic ID:0x68c1, revision:0x28, MMIOReg:0xffffc900117c0000.
Jul 14 23:49:21 localhost kernel: [ 8690.306405] FB phys addr: 0xd0000000, MC :0xf00000000, Total FB size :0x40000000.
Jul 14 23:49:21 localhost kernel: [ 8690.306407] gart table MC:0xf0f941000, Physical:0xdf941000, size:0x3be000.
Jul 14 23:49:21 localhost kernel: [ 8690.306410] mc_node :FB, total 1 zones
Jul 14 23:49:21 localhost kernel: [ 8690.306411]     MC start:0xf00000000, Physical:0xd0000000, size:0xfd00000.
Jul 14 23:49:21 localhost kernel: [ 8690.306414]     Mapped heap -- Offset:0x0, size:0xf941000, reference count:120, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306416]     Mapped heap -- Offset:0x0, size:0x1000000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306418]     Mapped heap -- Offset:0xf941000, size:0x3bf000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306419] mc_node :INV_FB, total 1 zones
Jul 14 23:49:21 localhost kernel: [ 8690.306421]     MC start:0xf0fd00000, Physical:0xdfd00000, size:0x30300000.
Jul 14 23:49:21 localhost kernel: [ 8690.306423]     Mapped heap -- Offset:0x302f4000, size:0xc000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306424] mc_node :GART_USWC, total 2 zones
Jul 14 23:49:21 localhost kernel: [ 8690.306426]     MC start:0x3ce90000, Physical:0x0, size:0x4ac00000.
Jul 14 23:49:21 localhost kernel: [ 8690.306428]     Mapped heap -- Offset:0x5030000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306430]     Mapped heap -- Offset:0x4830000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306432]     Mapped heap -- Offset:0x4030000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306434]     Mapped heap -- Offset:0x3830000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306436]     Mapped heap -- Offset:0x3030000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306438]     Mapped heap -- Offset:0x2830000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306440]     Mapped heap -- Offset:0x2030000, size:0x800000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306442]     Mapped heap -- Offset:0x30000, size:0x2000000, reference count:13, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306444] mc_node :GART_CACHEABLE, total 3 zones
Jul 14 23:49:21 localhost kernel: [ 8690.306445]     MC start:0x10400000, Physical:0x0, size:0x2ca90000.
Jul 14 23:49:21 localhost kernel: [ 8690.306447]     Mapped heap -- Offset:0x8100000, size:0x700000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306449]     Mapped heap -- Offset:0x3f00000, size:0x600000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306451]     Mapped heap -- Offset:0x5800000, size:0x700000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306453]     Mapped heap -- Offset:0x5100000, size:0x700000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306455]     Mapped heap -- Offset:0x7b00000, size:0x600000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306457]     Mapped heap -- Offset:0x7500000, size:0x600000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306459]     Mapped heap -- Offset:0x6f00000, size:0x600000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306461]     Mapped heap -- Offset:0x6900000, size:0x600000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306463]     Mapped heap -- Offset:0x6300000, size:0x600000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306465]     Mapped heap -- Offset:0x4500000, size:0x600000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306467]     Mapped heap -- Offset:0x4d00000, size:0x400000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306469]     Mapped heap -- Offset:0x3c00000, size:0x300000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306471]     Mapped heap -- Offset:0x3500000, size:0x700000, reference count:3, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306473]     Mapped heap -- Offset:0x2e00000, size:0x700000, reference count:2, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306475]     Mapped heap -- Offset:0x2700000, size:0x700000, reference count:3, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306477]     Mapped heap -- Offset:0x2300000, size:0x400000, reference count:5, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306479]     Mapped heap -- Offset:0x1f00000, size:0x400000, reference count:6, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306481]     Mapped heap -- Offset:0x1b00000, size:0x400000, reference count:5, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306483]     Mapped heap -- Offset:0x1700000, size:0x400000, reference count:3, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306485]     Mapped heap -- Offset:0x1000000, size:0x700000, reference count:17, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306487]     Mapped heap -- Offset:0x900000, size:0x700000, reference count:16, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306489]     Mapped heap -- Offset:0x200000, size:0x700000, reference count:40, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306491]     Mapped heap -- Offset:0x0, size:0x200000, reference count:16, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306494]     Mapped heap -- Offset:0xef000, size:0x11000, reference count:1, mapping count:0,
Jul 14 23:49:21 localhost kernel: [ 8690.306552] GRBM : 0x3828, SRBM : 0x200000c0 .
Jul 14 23:49:21 localhost kernel: [ 8690.306555] CP_RB_BASE : 0x3cec00, CP_RB_RPTR : 0x20 , CP_RB_WPTR :0x20.
Jul 14 23:49:21 localhost kernel: [ 8690.306558] CP_IB1_BUFSZ:0x0, CP_IB1_BASE_HI:0x0, CP_IB1_BASE_LO:0x0.
Jul 14 23:49:21 localhost kernel: [ 8690.306560] last submit IB buffer -- MC :0x0. Can't found mapped physical page for this MC .
Jul 14 23:49:21 localhost kernel: [ 8690.306561] Dump the trace queue.
Jul 14 23:49:21 localhost kernel: [ 8690.306562] End of dump

Too bad we still don't know what's the cause... and why it seemed to be fine for so many days.

Update: Well, this is weird and worse and perhaps related. The first time that this happened to me was a few days ago. After 15 minutes of inactivity, the screen turns off, to save power, well, I think this is the third time that, when returning to the laptop and attempting to turn the screen back on, it doesn't. It's like the same thing that happens when resuming from sleep mode, the black screen.
I checked the logs, and in everything.log, these are the only entries from about that time:
Jul 15 18:03:01 localhost crond[820]: FILE /var/spool/cron/root USER root PID 12357 job sys-hourly
Jul 15 18:08:13 localhost kernel: [13935.470836] ath: txq: ffff88013002e8d0 axq_qnum: 2, mac80211_qnum: 2 axq_link: ffff88003782c160 pending frames: 1 axq_acq empty: 1 stopped: 0 axq_depth: 0  Attempting to restart tx logic.
Jul 15 18:15:44 localhost -- MARK --
Jul 15 18:32:02 localhost kernel: [15360.917332] SysRq : Keyboard mode set to system default
Jul 15 18:32:50 localhost kernel: [15408.634134] SysRq : Terminate All Tasks
(...)
Oh, this is so confusing, haha.

Last edited by AnnS (2011-07-15 23:18:49)

AnnS · 2011-07-19 19:55:01

Hey! Yesterday I was watching a few videos on YouTube, and I don't normally do this, but when I tried to watch one of them at full screen, not only it was painfully slow, but at some point it completely freezed, completely freezed with the image of the video there. I couldn't use the computer anymore. Tried switching TTYs, Ctrl+Atl+Backspace to kill X and nothing. I even tried running a few commands from my other PC through SSH, like running init 3 and nothing. Total hang. I checked the logs, and there was again the "ASIC hang happened". So maybe it's related and it is the driver Catalyst. Later, I tried watching other videos in full screen, but it didn't freeze, but still it was slow.

Also, I must mention that I installed Sabayon, with GNOME, to try a few things, and with the Catalyst driver both times I tried to resume from suspend/sleep mode, the same thing with the black screen and total hang happened. Watching YouTube videos is fine, if I don't enable Compiz that is. With Metacity there were no problems, but with Compiz enabled, it was also slow, though it didn't freeze the times I tried. Seeing that, in Arch, with KDE, I tried disabling desktop effects, but that didn't help.

This is the log from when the problem with the YouTube video happened:

Jul 18 02:38:09 localhost kernel: [ 7140.312586] [fglrx] ASIC hang happened
Jul 18 02:38:09 localhost kernel: [ 7140.312595] Pid: 869, comm: X Tainted: P            2.6.39-ARCH #1
Jul 18 02:38:09 localhost kernel: [ 7140.312599] Call Trace:
Jul 18 02:38:09 localhost kernel: [ 7140.312663]  [<ffffffffa0145d5e>] KCL_DEBUG_OsDump+0xe/0x10 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.312705]  [<ffffffffa015318c>] firegl_hardwareHangRecovery+0x1c/0x50 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.312781]  [<ffffffffa01da419>] ? _ZN4Asic9WaitUntil15ResetASICIfHungEv+0x9/0x10 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.312857]  [<ffffffffa01da3cc>] ? _ZN4Asic9WaitUntil15WaitForCompleteEv+0x6c/0xb0 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.312933]  [<ffffffffa01d9334>] ? _ZN15ExecutableUnits10CPRingIdleE15idle_WaitMethod12_QS_CP_RING_+0xe4/0x1a0 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313010]  [<ffffffffa01d91fb>] ? _ZN15ExecutableUnits7PM4idleE15idle_WaitMethod+0x4b/0x90 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313086]  [<ffffffffa01d8eae>] ? _ZN15ExecutableUnits9assertPM4Eb+0x1e/0x70 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313160]  [<ffffffffa01e0720>] ? _ZN8AsicR6009assertPM4Eb+0x40/0x70 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313209]  [<ffffffffa01703e0>] ? firegl_cmmqs_enableqs+0x90/0x90 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313277]  [<ffffffffa01b5256>] ? CMMQS_DisableQS+0x16/0x20 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313325]  [<ffffffffa01703e0>] ? firegl_cmmqs_enableqs+0x90/0x90 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313374]  [<ffffffffa0172032>] ? firegl_cmmqs_Disable_QS+0x62/0x80 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313421]  [<ffffffffa0170400>] ? firegl_cmmqs_disableqs+0x20/0x90 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313451]  [<ffffffffa0141256>] ? KCL_PosixSecurityCapCheck+0x26/0x30 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313490]  [<ffffffffa014ed98>] ? firegl_ioctl+0x1e8/0x250 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313499]  [<ffffffff8118ae62>] ? fsnotify+0x1c2/0x2a0
Jul 18 02:38:09 localhost kernel: [ 7140.313526]  [<ffffffffa013feae>] ? ip_firegl_unlocked_ioctl+0xe/0x20 [fglrx]
Jul 18 02:38:09 localhost kernel: [ 7140.313535]  [<ffffffff81163b6f>] ? do_vfs_ioctl+0x8f/0x500
Jul 18 02:38:09 localhost kernel: [ 7140.313541]  [<ffffffff81151f48>] ? vfs_write+0x148/0x180
Jul 18 02:38:09 localhost kernel: [ 7140.313547]  [<ffffffff81164071>] ? sys_ioctl+0x91/0xa0
Jul 18 02:38:09 localhost kernel: [ 7140.313553]  [<ffffffff813e8ec2>] ? system_call_fastpath+0x16/0x1b
Jul 18 02:38:09 localhost kernel: [ 7140.313560] pubdev:0xffffffffa0387080, num of device:1 , name:fglrx, major 8, minor 86. 
Jul 18 02:38:09 localhost kernel: [ 7140.313565] device 0 : 0xffff880130380000 .
Jul 18 02:38:09 localhost kernel: [ 7140.313568] Asic ID:0x68c1, revision:0x28, MMIOReg:0xffffc90011780000.
Jul 18 02:38:09 localhost kernel: [ 7140.313573] FB phys addr: 0xd0000000, MC :0xf00000000, Total FB size :0x40000000.
Jul 18 02:38:09 localhost kernel: [ 7140.313577] gart table MC:0xf0f941000, Physical:0xdf941000, size:0x3be000.
Jul 18 02:38:09 localhost kernel: [ 7140.313582] mc_node :FB, total 1 zones
Jul 18 02:38:09 localhost kernel: [ 7140.313585]     MC start:0xf00000000, Physical:0xd0000000, size:0xfd00000.
Jul 18 02:38:09 localhost kernel: [ 7140.313590]     Mapped heap -- Offset:0x0, size:0xf941000, reference count:123, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313595]     Mapped heap -- Offset:0x0, size:0x1000000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313599]     Mapped heap -- Offset:0xf941000, size:0x3bf000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313603] mc_node :INV_FB, total 1 zones
Jul 18 02:38:09 localhost kernel: [ 7140.313607]     MC start:0xf0fd00000, Physical:0xdfd00000, size:0x30300000.
Jul 18 02:38:09 localhost kernel: [ 7140.313611]     Mapped heap -- Offset:0x302f4000, size:0xc000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313615] mc_node :GART_USWC, total 2 zones
Jul 18 02:38:09 localhost kernel: [ 7140.313618]     MC start:0x3ce90000, Physical:0x0, size:0x4ac00000.
Jul 18 02:38:09 localhost kernel: [ 7140.313623]     Mapped heap -- Offset:0x30000, size:0x2000000, reference count:8, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313627] mc_node :GART_CACHEABLE, total 3 zones
Jul 18 02:38:09 localhost kernel: [ 7140.313630]     MC start:0x10400000, Physical:0x0, size:0x2ca90000.
Jul 18 02:38:09 localhost kernel: [ 7140.313634]     Mapped heap -- Offset:0x9300000, size:0x700000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313639]     Mapped heap -- Offset:0x8c00000, size:0x700000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313644]     Mapped heap -- Offset:0x8600000, size:0x600000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313649]     Mapped heap -- Offset:0x8000000, size:0x600000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313653]     Mapped heap -- Offset:0x7a00000, size:0x600000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313658]     Mapped heap -- Offset:0x7400000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313663]     Mapped heap -- Offset:0x6800000, size:0x300000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313668]     Mapped heap -- Offset:0x6e00000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313672]     Mapped heap -- Offset:0x6200000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313677]     Mapped heap -- Offset:0x5a00000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313682]     Mapped heap -- Offset:0x5400000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313686]     Mapped heap -- Offset:0x4e00000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313691]     Mapped heap -- Offset:0x4800000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313696]     Mapped heap -- Offset:0x3c00000, size:0x600000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313700]     Mapped heap -- Offset:0x4200000, size:0x600000, reference count:4, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313705]     Mapped heap -- Offset:0x3500000, size:0x700000, reference count:2, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313710]     Mapped heap -- Offset:0x2e00000, size:0x700000, reference count:4, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313715]     Mapped heap -- Offset:0x2700000, size:0x700000, reference count:3, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313719]     Mapped heap -- Offset:0x2300000, size:0x400000, reference count:3, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313724]     Mapped heap -- Offset:0x1f00000, size:0x400000, reference count:8, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313729]     Mapped heap -- Offset:0x1b00000, size:0x400000, reference count:7, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313733]     Mapped heap -- Offset:0x1700000, size:0x400000, reference count:7, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313738]     Mapped heap -- Offset:0x1000000, size:0x700000, reference count:20, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313743]     Mapped heap -- Offset:0x900000, size:0x700000, reference count:20, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313747]     Mapped heap -- Offset:0x200000, size:0x700000, reference count:28, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313752]     Mapped heap -- Offset:0x0, size:0x200000, reference count:15, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313757]     Mapped heap -- Offset:0xef000, size:0x11000, reference count:1, mapping count:0,
Jul 18 02:38:09 localhost kernel: [ 7140.313824] GRBM : 0xa0003828, SRBM : 0x200000c0 .
Jul 18 02:38:09 localhost kernel: [ 7140.313829] CP_RB_BASE : 0x3cec00, CP_RB_RPTR : 0x1b350 , CP_RB_WPTR :0x1b3b0.
Jul 18 02:38:09 localhost kernel: [ 7140.313834] CP_IB1_BUFSZ:0x158, CP_IB1_BASE_HI:0x0, CP_IB1_BASE_LO:0x3d2ec000.
Jul 18 02:38:09 localhost kernel: [ 7140.313838] last submit IB buffer -- MC :0x3d2ec000,phys:0x122f3a000.
Jul 18 02:38:09 localhost kernel: [ 7140.313844] Dump the trace queue.
Jul 18 02:38:09 localhost kernel: [ 7140.313846] End of dump

Anyway, I'm now using the -git version of the open source driver (from the radeon repository), for the power management options, and I must say that I really like it. Watching videos, even in full screen in YouTube, is perfect. I don't care much about 3D, so that's not a problem for me. I'm using the 'mid' power profile and I don't notice any difference performance-wise so far. The temperature I think is almost the same as with the Catalyst driver. I even noticed that it uses less RAM than with the Catalyst driver. So far I'm good with this one. The only thing is the little distorted screen for a few seconds when logging into the WM. If I disable KMS, it doesn't happen, but KDE is not usable that way.

I'm still testing this driver, but I like it more than the proprietary driver, so I hope to not encounter problems.

Arch Linux

#1 2011-06-30 05:17:00

Sometimes it doesn't resume from suspend

#2 2011-06-30 07:07:43

Re: Sometimes it doesn't resume from suspend

#3 2011-06-30 13:41:57

Re: Sometimes it doesn't resume from suspend

#4 2011-07-01 01:47:55

Re: Sometimes it doesn't resume from suspend

#5 2011-07-01 13:33:00

Re: Sometimes it doesn't resume from suspend

#6 2011-07-01 15:52:37

Re: Sometimes it doesn't resume from suspend

#7 2011-07-01 23:54:42

Re: Sometimes it doesn't resume from suspend

#8 2011-07-02 00:35:44

Re: Sometimes it doesn't resume from suspend

#9 2011-07-02 08:24:17

Re: Sometimes it doesn't resume from suspend

#10 2011-07-02 22:22:28

Re: Sometimes it doesn't resume from suspend

#11 2011-07-09 01:04:42

Re: Sometimes it doesn't resume from suspend

#12 2011-07-09 06:50:22

Re: Sometimes it doesn't resume from suspend

#13 2011-07-15 05:01:00

Re: Sometimes it doesn't resume from suspend

#14 2011-07-19 19:55:01

Re: Sometimes it doesn't resume from suspend

Board footer