Bug 713128

Summary: long suspend freezes system / crashes gnome-shell
Product: [Fedora] Fedora Reporter: Jonathan <emailjonathananderson-fedora>
Component: gnome-shellAssignee: Owen Taylor <otaylor>
Status: CLOSED NOTABUG QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: high Docs Contact:
Priority: unspecified    
Version: 15CC: elad, maxamillion, otaylor, samkraju, walters
Target Milestone: ---   
Target Release: ---   
Hardware: x86_64   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2011-06-14 23:37:48 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:

Description Jonathan 2011-06-14 11:58:56 UTC 
Description of problem:
I'm listing this bug against gnome-shell as I've often seen a crash in gnome-shell in the logs in relation to this behavior. This might of course only be a symptom of the real problem.
long suspend freezes system / crashes gnome-shell. Upon resume, the cursor is often visible and can be moved. No response or extremely slow response to interaction and caps-lock. If I get enough response to successfully logout, the problem goes away but accomplishing this can take up to 5 minutes if it is even possible. A short suspend is usually successful.

Version-Release number of selected component (if applicable):
gnome-shell.x86_64  3.0.2-1.fc15

How reproducible:
90%

Steps to Reproduce:
1.suspend
2.wait for maybe an hour
3.resume
  
Actual results:
slow system on the verge of frozen. Garbled graphics that might clear up after some minutes wait.

Expected results:
Normal system.

Additional info:

Excert from messages from suspend until hard reset:
Jun 14 11:20:33 cinderella gdm-simple-greeter[1464]: Gtk-WARNING: gtk_widget_size_allocate(): attempt to allocate widget with width -47 and height -47
Jun 14 11:20:34 cinderella gdm-simple-greeter[1464]: Gtk-WARNING: gtk_widget_size_allocate(): attempt to allocate widget with width -47 and height -47
Jun 14 11:20:34 cinderella dbus: [system] Activating via systemd: service name='org.freedesktop.Accounts' unit='accounts-daemon.service'
Jun 14 11:20:34 cinderella dbus: [system] Successfully activated service 'org.freedesktop.Accounts'
Jun 14 11:20:34 cinderella accounts-daemon[1545]: started daemon version 0.6.10
Jun 14 11:20:34 cinderella accounts-daemon[1545]: ** (ck-history:1548): WARNING **: Unable to parse session added event: seat-id='Seat1' session-id='Session2' session-type='' session-x11-display=':0' session-x1277721225.796 type=SYSTEM_START : kernel-release='2.6.33.5-124.fc13.x86_64' boot-arguments='ro root=/dev/mapper/vg_cinderella-lv_root rd_LUKS_UUID=luks-e5fe3fb6-2f73-4149-a31a-82c1bb845473 rd_LVM_LV=vg_cinderella/lv_root rd_LVM_LV=vg_cinderella/lv_swap rd_NO_MD rd_NO_DM LANG=en_US.UTF-8 SYSFONT=latarcyrheb-sun16 KEYBOARDTYPE=pc KEYTABLE=sv-latin1'
Jun 14 11:20:34 cinderella accounts-daemon[1545]: ** (ck-history:1548): WARNING **: Unable to parse session changed event: seat-id='Sea1278450977.586 type=SYSTEM_START : kernel-release='2.6.33.5-124.fc13.x86_64' boot-arguments='ro root=/dev/mapper/vg_cinderella-lv_root rd_LUKS_UUID=luks-e5fe3fb6-2f73-4149-a31a-82c1bb845473 rd_LVM_LV=vg_cinderella/lv_root rd_LVM_LV=vg_cinderella/lv_swap rd_NO_MD rd_NO_DM LANG=en_US.UTF-8 SYSFONT=latarcyrheb-sun16 KEYBOARDTYPE=pc KEYTABLE=sv-latin1 3'
Jun 14 11:20:36 cinderella setroubleshoot: SELinux is preventing /usr/libexec/gnome-session-check-accelerated-helper from 'read, write' accesses on the chr_file nvidiactl. For complete SELinux messages. run sealert -l 5966d34a-5594-4f52-a9fa-2bde6f4354d1
Jun 14 11:20:37 cinderella dbus: [system] Activating service name='net.reactivated.Fprint' (using servicehelper)
Jun 14 11:20:37 cinderella dbus: [system] Successfully activated service 'net.reactivated.Fprint'
Jun 14 11:20:42 cinderella kernel: [   90.990623] fuse init (API version 7.16)
Jun 14 09:20:44 cinderella rtkit-daemon[1446]: Successfully made thread 1736 of process 1736 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jun 14 11:20:44 cinderella pulseaudio[1736]: pid.c: Stale PID file, overwriting.
Jun 14 09:20:44 cinderella rtkit-daemon[1446]: Successfully made thread 1739 of process 1736 (/usr/bin/pulseaudio) owned by '500' RT at priority 5.
Jun 14 09:20:44 cinderella rtkit-daemon[1446]: Successfully made thread 1745 of process 1736 (/usr/bin/pulseaudio) owned by '500' RT at priority 5.
Jun 14 09:20:44 cinderella rtkit-daemon[1446]: Successfully made thread 1746 of process 1736 (/usr/bin/pulseaudio) owned by '500' RT at priority 5.
Jun 14 09:20:45 cinderella rtkit-daemon[1446]: Successfully made thread 1755 of process 1736 (/usr/bin/pulseaudio) owned by '500' RT at priority 5.
Jun 14 11:20:45 cinderella dbus: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Jun 14 09:20:45 cinderella rtkit-daemon[1446]: Successfully made thread 1762 of process 1762 (/usr/bin/pulseaudio) owned by '500' high priority at nice level -11.
Jun 14 11:20:45 cinderella pulseaudio[1762]: pid.c: Daemon already running.
Jun 14 11:20:46 cinderella dbus: [system] Successfully activated service 'org.freedesktop.PackageKit'
Jun 14 11:20:58 cinderella bluetoothd[933]: Can't init device hci0: Operation not possible due to RF-kill (132)
Jun 14 11:20:58 cinderella bluetoothd[933]: bluetoothd[933]: Can't init device hci0: Operation not possible due to RF-kill (132)
Jun 14 11:20:58 cinderella dbus: [system] Rejected send message, 2 matched rules; type="error", sender=":1.0" (uid=0 pid=933 comm="/usr/sbin/bluetoothd -n ") interface="(unset)" member="(unset)" error name="org.bluez.Error.Failed" requested_reply=0 destination=":1.69" (uid=500 pid=1765 comm="/usr/bin/gnome-shell "))
Jun 14 11:35:30 cinderella ntpd[938]: 0.0.0.0 c612 02 freq_set kernel -31.991 PPM
Jun 14 11:35:30 cinderella ntpd[938]: 0.0.0.0 c615 05 clock_sync
Jun 14 11:42:34 cinderella dbus: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Jun 14 11:42:34 cinderella dbus: [system] Successfully activated service 'org.freedesktop.PackageKit'
Jun 14 11:44:26 cinderella kernel: [ 1514.737083] cfg80211: Calling CRDA to update world regulatory domain
Jun 14 11:44:26 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: completed -> disconnected
Jun 14 11:44:26 cinderella kernel: [ 1514.776448] cfg80211: World regulatory domain updated:
Jun 14 11:44:26 cinderella kernel: [ 1514.776454] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Jun 14 11:44:26 cinderella kernel: [ 1514.776458] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.776462] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.776465] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.776469] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.776472] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.776485] cfg80211: Calling CRDA for country: SE
Jun 14 11:44:26 cinderella kernel: [ 1514.788350] cfg80211: Regulatory domain changed to country: SE
Jun 14 11:44:26 cinderella kernel: [ 1514.788354] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Jun 14 11:44:26 cinderella kernel: [ 1514.788358] cfg80211:     (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.788362] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.788365] cfg80211:     (5250000 KHz - 5330000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Jun 14 11:44:26 cinderella kernel: [ 1514.788368] cfg80211:     (5490000 KHz - 5710000 KHz @ 40000 KHz), (N/A, 2700 mBm)
Jun 14 11:44:26 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jun 14 11:44:27 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jun 14 11:44:27 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: authenticating -> associating
Jun 14 11:44:27 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: associating -> completed
Jun 14 12:27:37 cinderella dbus: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Jun 14 12:27:37 cinderella dbus: [system] Successfully activated service 'org.freedesktop.PackageKit'
Jun 14 12:27:38 cinderella kernel: [ 4107.469811] TCP lp registered
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> sleep requested (sleeping: no  enabled: yes)
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> sleeping or disabling...
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (wlan0): now unmanaged
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (wlan0): deactivating device (reason: 37).
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 1175
Jun 14 12:57:39 cinderella avahi-daemon[934]: Withdrawing address record for 192.168.0.110 on wlan0.
Jun 14 12:57:39 cinderella avahi-daemon[934]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.110.
Jun 14 12:57:39 cinderella avahi-daemon[934]: Interface wlan0.IPv4 no longer relevant for mDNS.
Jun 14 12:57:39 cinderella kernel: [ 5908.223730] cfg80211: Calling CRDA to update world regulatory domain
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (wlan0): cleaning up...
Jun 14 12:57:39 cinderella kernel: [ 5908.275439] cfg80211: World regulatory domain updated:
Jun 14 12:57:39 cinderella kernel: [ 5908.275444] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Jun 14 12:57:39 cinderella kernel: [ 5908.275449] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.275452] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.275456] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.275460] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.275463] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.275476] cfg80211: Calling CRDA for country: SE
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (wlan0): taking down device.
Jun 14 12:57:39 cinderella kernel: [ 5908.319591] cfg80211: Regulatory domain changed to country: SE
Jun 14 12:57:39 cinderella kernel: [ 5908.319596] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Jun 14 12:57:39 cinderella kernel: [ 5908.319600] cfg80211:     (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.319604] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.319607] cfg80211:     (5250000 KHz - 5330000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Jun 14 12:57:39 cinderella kernel: [ 5908.319610] cfg80211:     (5490000 KHz - 5710000 KHz @ 40000 KHz), (N/A, 2700 mBm)
Jun 14 12:57:39 cinderella avahi-daemon[934]: Withdrawing address record for fe80::1e4b:d6ff:fe5b:5ecb on wlan0.
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (eth0): now unmanaged
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (eth0): cleaning up...
Jun 14 12:57:39 cinderella NetworkManager[942]: <info> (eth0): taking down device.
Jun 14 12:57:39 cinderella dbus: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Jun 14 12:57:39 cinderella dbus: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 14 12:57:41 cinderella ntpd[938]: Deleting interface #5 wlan0, 192.168.0.110#123, interface stats: received=289, sent=291, dropped=0, active_time=5847 secs
Jun 14 12:57:41 cinderella ntpd[938]: 130.236.254.17 interface 192.168.0.110 -> (none)
Jun 14 12:57:41 cinderella ntpd[938]: 178.73.198.130 interface 192.168.0.110 -> (none)
Jun 14 12:57:41 cinderella ntpd[938]: 178.78.255.254 interface 192.168.0.110 -> (none)
Jun 14 12:57:41 cinderella ntpd[938]: 83.209.46.28 interface 192.168.0.110 -> (none)
Jun 14 12:57:41 cinderella ntpd[938]: Deleting interface #4 wlan0, fe80::1e4b:d6ff:fe5b:5ecb#123, interface stats: received=0, sent=0, dropped=0, active_time=5849 secs
Jun 14 12:57:41 cinderella ntpd[938]: peers refreshed
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: The canary thread is apparently starving. Taking action.
Jun 14 13:21:03 cinderella kernel: [ 5912.606439] PM: Syncing filesystems ... done.
Jun 14 13:21:03 cinderella kernel: [ 5912.621210] Freezing user space processes ... (elapsed 0.01 seconds) done.
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Demoting known real-time threads.
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Successfully demoted thread 1755 of process 1736 (/usr/bin/pulseaudio).
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Successfully demoted thread 1746 of process 1736 (/usr/bin/pulseaudio).
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Successfully demoted thread 1745 of process 1736 (/usr/bin/pulseaudio).
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Successfully demoted thread 1739 of process 1736 (/usr/bin/pulseaudio).
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Successfully demoted thread 1736 of process 1736 (/usr/bin/pulseaudio).
Jun 14 11:21:03 cinderella rtkit-daemon[1446]: Demoted 5 threads.
Jun 14 13:21:03 cinderella kernel: [ 5912.632171] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Jun 14 13:21:03 cinderella kernel: [ 5912.643179] Suspending console(s) (use no_console_suspend to debug)
Jun 14 13:21:03 cinderella kernel: [ 5912.658716] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jun 14 13:21:03 cinderella kernel: [ 5912.659306] ata_piix 0000:00:1f.5: PCI INT B disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.659430] ehci_hcd 0000:00:1d.7: PCI INT A disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.659446] uhci_hcd 0000:00:1d.2: PCI INT C disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.659459] uhci_hcd 0000:00:1d.1: PCI INT B disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.659474] uhci_hcd 0000:00:1d.0: PCI INT A disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.660206] ehci_hcd 0000:00:1a.7: PCI INT C disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.660221] uhci_hcd 0000:00:1a.2: PCI INT D disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.660232] uhci_hcd 0000:00:1a.1: PCI INT B disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.660249] uhci_hcd 0000:00:1a.0: PCI INT A disabled
Jun 14 13:21:03 cinderella kernel: [ 5912.670601] sd 0:0:0:0: [sda] Stopping disk
Jun 14 13:21:03 cinderella kernel: [ 5912.761119] HDA Intel 0000:00:1b.0: PCI INT A disabled
Jun 14 13:21:03 cinderella kernel: [ 5913.061221] ata_piix 0000:00:1f.2: PCI INT B disabled
Jun 14 13:21:03 cinderella kernel: [ 5913.074048] HDA Intel 0000:01:00.1: PCI INT A disabled
Jun 14 13:21:03 cinderella kernel: [ 5913.085047] pciehp 0000:00:01.0:pcie04: pciehp_suspend ENTRY
Jun 14 13:21:03 cinderella kernel: [ 5913.085074] PM: suspend of devices complete after 441.353 msecs
Jun 14 13:21:03 cinderella kernel: [ 5913.085351] atl1c 0000:04:00.0: wake-up capability enabled by ACPI
Jun 14 13:21:03 cinderella kernel: [ 5913.129197] PM: late suspend of devices complete after 44.117 msecs
Jun 14 13:21:03 cinderella kernel: [ 5913.129669] ACPI: Preparing to enter system sleep state S3
Jun 14 13:21:03 cinderella kernel: [ 5913.369649] PM: Saving platform NVS memory
Jun 14 13:21:03 cinderella kernel: [ 5913.370647] Disabling non-boot CPUs ...
Jun 14 13:21:03 cinderella kernel: [ 5913.372160] CPU 1 is now offline
Jun 14 13:21:03 cinderella kernel: [ 5913.372629] Extended CMOS year: 2000
Jun 14 13:21:03 cinderella kernel: [ 5913.372629] PM: Restoring platform NVS memory
Jun 14 13:21:03 cinderella kernel: [ 5913.372629] microcode: CPU0 updated to revision 0xa0b, date = 2010-09-28
Jun 14 13:21:03 cinderella kernel: [ 5913.372629] Extended CMOS year: 2000
Jun 14 13:21:03 cinderella kernel: [ 5913.372629] Enabling non-boot CPUs ...
Jun 14 13:21:03 cinderella kernel: [ 5913.372629] Booting Node 0 Processor 1 APIC 0x1
Jun 14 13:21:03 cinderella kernel: [ 5913.443364] NMI watchdog enabled, takes one hw-pmu counter.
Jun 14 13:21:03 cinderella kernel: [ 5913.444420] microcode: CPU1 updated to revision 0xa0b, date = 2010-09-28
Jun 14 13:21:03 cinderella kernel: [ 5913.451123] Switched to NOHz mode on CPU #1
Jun 14 13:21:03 cinderella kernel: [ 5913.452077] CPU1 is up
Jun 14 13:21:03 cinderella kernel: [ 5913.453848] ACPI: Waking up from system sleep state S3
Jun 14 13:21:03 cinderella kernel: [ 5914.806351] power_supply BAT0: parent PNP0C0A:00 should not be sleeping
Jun 14 13:21:03 cinderella bluetoothd[933]: HCI dev 0 unregistered
Jun 14 13:21:03 cinderella bluetoothd[933]: Stopping hci0 event socket
Jun 14 13:21:03 cinderella bluetoothd[933]: Unregister path: /org/bluez/933/hci0
Jun 14 13:21:03 cinderella kernel: [ 5914.921740] PM: early resume of devices complete after 1.597 msecs
Jun 14 13:21:03 cinderella kernel: [ 5914.921829] pciehp 0000:00:01.0:pcie04: pciehp_resume ENTRY
Jun 14 13:21:03 cinderella kernel: [ 5914.921841] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Jun 14 13:21:03 cinderella kernel: [ 5914.921879] usb usb3: root hub lost power or was reset
Jun 14 13:21:03 cinderella kernel: [ 5914.921894] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Jun 14 13:21:03 cinderella kernel: [ 5914.921915] uhci_hcd 0000:00:1a.2: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Jun 14 13:21:03 cinderella kernel: [ 5914.921931] usb usb4: root hub lost power or was reset
Jun 14 13:21:03 cinderella kernel: [ 5914.921949] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Jun 14 13:21:03 cinderella kernel: [ 5914.921962] usb usb5: root hub lost power or was reset
Jun 14 13:21:03 cinderella kernel: [ 5914.921998] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Jun 14 13:21:03 cinderella kernel: [ 5914.922051] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Jun 14 13:21:03 cinderella kernel: [ 5914.922097] usb usb6: root hub lost power or was reset
Jun 14 13:21:03 cinderella kernel: [ 5914.922112] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Jun 14 13:21:03 cinderella kernel: [ 5914.922157] usb usb7: root hub lost power or was reset
Jun 14 13:21:03 cinderella kernel: [ 5914.922162] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Jun 14 13:21:03 cinderella kernel: [ 5914.922175] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Jun 14 13:21:03 cinderella kernel: [ 5914.922202] usb usb8: root hub lost power or was reset
Jun 14 13:21:03 cinderella kernel: [ 5914.922225] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Jun 14 13:21:03 cinderella kernel: [ 5914.922229] ata_piix 0000:00:1f.5: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Jun 14 13:21:03 cinderella kernel: [ 5914.922253] HDA Intel 0000:01:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Jun 14 13:21:03 cinderella kernel: [ 5914.936858] atl1c 0000:04:00.0: wake-up capability disabled by ACPI
Jun 14 13:21:03 cinderella kernel: [ 5914.942835] sd 0:0:0:0: [sda] Starting disk
Jun 14 13:21:03 cinderella kernel: [ 5915.085037] usb 2-5: reset high speed USB device using ehci_hcd and address 4
Jun 14 13:21:03 cinderella kernel: [ 5915.396155] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Jun 14 13:21:03 cinderella kernel: [ 5915.399481] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out
Jun 14 13:21:03 cinderella kernel: [ 5915.399485] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Jun 14 13:21:03 cinderella kernel: [ 5915.405047] usb 6-2: reset full speed USB device using uhci_hcd and address 2
Jun 14 13:21:03 cinderella kernel: [ 5915.405498] ata2.00: configured for UDMA/100
Jun 14 13:21:03 cinderella kernel: [ 5915.632045] usb 7-1: reset full speed USB device using uhci_hcd and address 2
Jun 14 13:21:03 cinderella kernel: [ 5915.769650] btusb 7-1:1.0: no reset_resume for driver btusb?
Jun 14 13:21:03 cinderella kernel: [ 5915.769654] btusb 7-1:1.1: no reset_resume for driver btusb?
Jun 14 13:21:03 cinderella kernel: [ 5916.010154] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 14 13:21:03 cinderella kernel: [ 5916.013483] ata1.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out
Jun 14 13:21:03 cinderella kernel: [ 5916.013487] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Jun 14 13:21:03 cinderella kernel: [ 5916.090286] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 14 13:21:03 cinderella kernel: [ 5916.348551] ata1.00: configured for UDMA/133
Jun 14 13:21:03 cinderella kernel: [ 5917.824782] PM: resume of devices complete after 2902.994 msecs
Jun 14 13:21:03 cinderella kernel: [ 5917.836508] Restarting tasks ... 
Jun 14 13:21:03 cinderella kernel: [ 5917.836564] hub 2-0:1.0: over-current change on port 5
Jun 14 13:21:03 cinderella bluetoothd[933]: HCI dev 0 registered
Jun 14 13:21:03 cinderella bluetoothd[933]: Listening for HCI events on hci0
Jun 14 13:21:03 cinderella bluetoothd[7766]: Can't init device hci0: Operation not possible due to RF-kill (132)
Jun 14 13:21:03 cinderella bluetoothd[933]: bluetoothd[933]: HCI dev 0 unregistered
Jun 14 13:21:03 cinderella bluetoothd[933]: bluetoothd[933]: Stopping hci0 event socket
Jun 14 13:21:03 cinderella bluetoothd[933]: bluetoothd[933]: Unregister path: /org/bluez/933/hci0
Jun 14 13:21:03 cinderella bluetoothd[933]: bluetoothd[933]: HCI dev 0 registered
Jun 14 13:21:03 cinderella bluetoothd[933]: bluetoothd[933]: Listening for HCI events on hci0
Jun 14 13:21:03 cinderella bluetoothd[933]: bluetoothd[7766]: Can't init device hci0: Operation not possible due to RF-kill (132)
Jun 14 13:21:03 cinderella kernel: [ 5917.880318] done.
Jun 14 13:21:03 cinderella dbus: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=500 pid=1765 comm="/usr/bin/gnome-shell ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply=0 destination=":1.0" (uid=0 pid=933 comm="/usr/sbin/bluetoothd -n "))
Jun 14 13:21:03 cinderella kernel: [ 5917.928661] video LNXVIDEO:01: Restoring backlight state
Jun 14 13:21:03 cinderella systemd[1]: Service bluetooth.target is not needed anymore. Stopping.
Jun 14 13:21:03 cinderella kernel: [ 5917.937337] hub 5-0:1.0: over-current change on port 2
Jun 14 13:21:03 cinderella kernel: [ 5918.038069] hub 8-0:1.0: over-current change on port 1
Jun 14 13:21:03 cinderella kernel: [ 5918.139203] hub 7-0:1.0: over-current change on port 1
Jun 14 13:21:03 cinderella dbus: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Jun 14 13:21:03 cinderella kernel: [ 5918.240042] hub 7-0:1.0: over-current change on port 2
Jun 14 13:21:03 cinderella dbus: [system] Successfully activated service 'org.freedesktop.PackageKit'
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> wake requested (sleeping: yes  enabled: yes)
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> waking up and re-enabling...
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> WWAN now enabled by management service
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): now managed
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): bringing up device.
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): preparing device.
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): deactivating device (reason: 2).
Jun 14 13:21:04 cinderella kernel: [ 5918.748174] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (eth0): now managed
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (eth0): bringing up device.
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (eth0): preparing device.
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (eth0): deactivating device (reason: 2).
Jun 14 13:21:04 cinderella kernel: [ 5918.814449] ADDRCONF(NETDEV_UP): eth0: link is not ready
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: starting -> ready
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Jun 14 13:21:04 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: ready -> inactive
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) starting connection 'Auto dlinktop'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0/wireless): access point 'Auto dlinktop' has security, but secrets are required.
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0/wireless): connection 'Auto dlinktop' has security, and secrets exist.  No new secrets needed.
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: added 'ssid' value 'dlinktop'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: added 'scan_ssid' value '1'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: added 'bssid' value '00:24:01:6a:d3:10'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: added 'psk' value '<omitted>'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: added 'proto' value 'WPA RSN'
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> Config: set interface ap_scan to 1
Jun 14 13:21:06 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: inactive -> scanning
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: authenticating -> associating
Jun 14 13:21:07 cinderella kernel: [ 5921.935913] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> (wlan0): supplicant interface state: associating -> completed
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'dlinktop'.
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> dhclient started with pid 7838
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Jun 14 13:21:07 cinderella dhclient[7838]: Internet Systems Consortium DHCP Client 4.2.1-P1
Jun 14 13:21:07 cinderella dhclient[7838]: Copyright 2004-2011 Internet Systems Consortium.
Jun 14 13:21:07 cinderella dhclient[7838]: All rights reserved.
Jun 14 13:21:07 cinderella dhclient[7838]: For info, please visit https://www.isc.org/software/dhcp/
Jun 14 13:21:07 cinderella dhclient[7838]: 
Jun 14 13:21:07 cinderella NetworkManager[942]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Jun 14 13:21:07 cinderella dhclient[7838]: Listening on LPF/wlan0/1c:4b:d6:5b:5e:cb
Jun 14 13:21:07 cinderella dhclient[7838]: Sending on   LPF/wlan0/1c:4b:d6:5b:5e:cb
Jun 14 13:21:07 cinderella dhclient[7838]: Sending on   Socket/fallback
Jun 14 13:21:08 cinderella dhclient[7838]: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Jun 14 13:21:08 cinderella dhclient[7838]: DHCPACK from 192.168.0.1
Jun 14 13:21:08 cinderella dhclient[7838]: bound to 192.168.0.110 -- renewal in 2147483648 seconds.
Jun 14 13:21:08 cinderella NetworkManager[942]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Jun 14 13:21:08 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Jun 14 13:21:08 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Jun 14 13:21:08 cinderella NetworkManager[942]: <info>   address 192.168.0.110
Jun 14 13:21:08 cinderella NetworkManager[942]: <info>   prefix 24 (255.255.255.0)
Jun 14 13:21:08 cinderella NetworkManager[942]: <info>   gateway 192.168.0.1
Jun 14 13:21:08 cinderella NetworkManager[942]: <info>   nameserver '192.168.0.1'
Jun 14 13:21:08 cinderella NetworkManager[942]: <info>   domain name 'dlinkrouter'
Jun 14 13:21:08 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
Jun 14 13:21:08 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Jun 14 13:21:08 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Jun 14 13:21:08 cinderella avahi-daemon[934]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.110.
Jun 14 13:21:08 cinderella avahi-daemon[934]: New relevant interface wlan0.IPv4 for mDNS.
Jun 14 13:21:08 cinderella avahi-daemon[934]: Registering new address record for 192.168.0.110 on wlan0.IPv4.
Jun 14 13:21:08 cinderella avahi-daemon[934]: Registering new address record for fe80::1e4b:d6ff:fe5b:5ecb on wlan0.*.
Jun 14 13:21:09 cinderella NetworkManager[942]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Jun 14 13:21:09 cinderella NetworkManager[942]: <info> Policy set 'Auto dlinktop' (wlan0) as default for IPv4 routing and DNS.
Jun 14 13:21:09 cinderella NetworkManager[942]: <info> Activation (wlan0) successful, device activated.
Jun 14 13:21:09 cinderella dbus: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Jun 14 13:21:09 cinderella NetworkManager[942]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Jun 14 13:21:09 cinderella dbus: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 14 13:21:10 cinderella ntpd[938]: Listen normally on 6 wlan0 192.168.0.110 UDP 123
Jun 14 13:21:10 cinderella ntpd[938]: Listen normally on 7 wlan0 fe80::1e4b:d6ff:fe5b:5ecb UDP 123
Jun 14 13:21:10 cinderella ntpd[938]: peers refreshed
Jun 14 13:21:11 cinderella ntpd[938]: 0.0.0.0 0618 08 no_sys_peer
Jun 14 13:21:55 cinderella kernel: [ 5970.326114] NVRM: Xid (0000:01:00): 13, 0004 00000000 00008597 000015e0 00000000 00000040
Jun 14 13:25:49 cinderella kernel: imklog 5.7.9, log source = /proc/kmsg started.
Jun 14 13:25:49 cinderella rsyslogd: [origin software="rsyslogd" swVersion="5.7.9" x-pid="1027" x-info="http://www.rsyslog.com"] start
Comment 1 Jonathan 2011-06-14 12:00:24 UTC 
It might and might not be related to this bug:
https://bugzilla.redhat.com/show_bug.cgi?id=709312

I have different hardware: Nvidia G210M
Comment 2 Elad Alfassa 2011-06-14 13:39:05 UTC 
Thanks for the bug report.  We have reviewed the information you have provided above, and there is some additional information we require that will be helpful in our diagnosis of this issue.

Please add drm.debug=0x04 to the kernel command line, restart computer, and attach

* your X server config file (/etc/X11/xorg.conf, if available),
* X server log file (/var/log/Xorg.*.log)
* output of the dmesg command, and
* system log (/var/log/messages)

to the bug report as individual uncompressed file attachments using the bugzilla file attachment link above.

We will review this issue again once you've had a chance to attach this information.

Thanks in advance.



-- 
Fedora Bugzappers volunteer triage team
https://fedoraproject.org/wiki/BugZappers
Comment 3 Jonathan 2011-06-14 23:37:48 UTC 
My bad. This seems to be a recognised bug of the nvidia proprietary driver which they claim to have fixed in the recent release.