Attachment 100827 Details for Bug 79890 – DMESG log at time of GPU hang (with leading retire_playback_urb messages removed)

DMESG log at time of GPU hang (with leading retire_playback_urb messages removed)

dmesg.txt (text/plain), 16.98 KB, created by Gunter Ohrner on 2014-06-10 15:32:24 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Gunter Ohrner

Created: 2014-06-10 15:32:24 UTC

Size: 16.98 KB

patch

obsolete

>[283531.844722] usb 3-3.3.3: USB disconnect, device number 32
>[283531.845298] sd 19:0:0:0: [sdb] Synchronizing SCSI cache
>[283531.845360] sd 19:0:0:0: [sdb]  
>[283531.845364] Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
>[283532.100659] usb 3-3.4: USB disconnect, device number 34
>[283534.008379] usb 3-1: USB disconnect, device number 37
>[283600.375322] PM: Syncing filesystems ... done.
>[283600.405092] PM: Preparing system for mem sleep
>[283600.409529] Freezing user space processes ... (elapsed 0.001 seconds) done.
>[283600.411311] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
>[283600.412547] PM: Entering mem sleep
>[283600.412593] Suspending console(s) (use no_console_suspend to debug)
>[283600.412754] wlan0: deauthenticating from 00:0b:6b:30:32:b5 by local choice (reason=3)
>[283600.439995] cfg80211: Calling CRDA to update world regulatory domain
>[283600.440058] sd 0:0:0:0: [sda] Synchronizing SCSI cache
>[283600.440383] sd 0:0:0:0: [sda] Stopping disk
>[283600.441523] option1 ttyUSB1: option_instat_callback: error -2
>[283601.381342] PM: suspend of devices complete after 968.951 msecs
>[283601.381486] PM: late suspend of devices complete after 0.142 msecs
>[283601.381647] r8169 0000:04:00.0: System wakeup enabled by ACPI
>[283601.413371] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
>[283601.429449] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
>[283601.461253] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
>[283601.477272] PM: noirq suspend of devices complete after 95.815 msecs
>[283601.477475] ACPI: Preparing to enter system sleep state S3
>[283601.477628] PM: Saving platform NVS memory
>[283601.478120] Disabling non-boot CPUs ...
>[283601.479361] kvm: disabling virtualization on CPU1
>[283601.581168] smpboot: CPU 1 is now offline
>[283601.582648] kvm: disabling virtualization on CPU2
>[283601.685125] smpboot: CPU 2 is now offline
>[283601.686567] kvm: disabling virtualization on CPU3
>[283601.789126] smpboot: CPU 3 is now offline
>[283601.790351] ACPI: Low-level resume complete
>[283601.790397] PM: Restoring platform NVS memory
>[283601.790885] Enabling non-boot CPUs ...
>[283601.790918] x86: Booting SMP configuration:
>[283601.790918] smpboot: Booting Node 0 Processor 1 APIC 0x1
>[283601.818870] kvm: enabling virtualization on CPU1
>[283601.821190] CPU1 is up
>[283601.821205] smpboot: Booting Node 0 Processor 2 APIC 0x2
>[283601.832472] kvm: enabling virtualization on CPU2
>[283601.834789] CPU2 is up
>[283601.834803] smpboot: Booting Node 0 Processor 3 APIC 0x3
>[283601.846164] kvm: enabling virtualization on CPU3
>[283601.848497] CPU3 is up
>[283601.851702] ACPI: Waking up from system sleep state S3
>[283601.882541] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
>[283601.914535] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
>[283601.946519] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
>[283602.010589] PM: noirq resume of devices complete after 156.781 msecs
>[283602.010701] PM: early resume of devices complete after 0.092 msecs
>[283602.010810] mei_me 0000:00:16.0: irq 45 for MSI/MSI-X
>[283602.011022] snd_hda_intel 0000:00:1b.0: irq 47 for MSI/MSI-X
>[283602.011177] r8169 0000:04:00.0: System wakeup disabled by ACPI
>[283602.426393] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[283602.428441] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[283602.428466] ata1.00: supports DRM functions and may not be fully accessible
>[283602.428562] ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
>[283602.428847] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[283602.428867] ata1.00: supports DRM functions and may not be fully accessible
>[283602.428905] ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
>[283602.428908] ata1.00: configured for UDMA/133
>[283602.434320] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
>[283602.435999] ata5.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[283602.437362] ata5.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[283602.437367] ata5.00: configured for UDMA/133
>[283602.450388] sd 0:0:0:0: [sda] Starting disk
>[283602.454681] iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S
>[283602.461407] iwlwifi 0000:02:00.0: Radio type=0x2-0x1-0x0
>[283602.555449] PM: resume of devices complete after 544.917 msecs
>[283602.555734] PM: Finishing wakeup.
>[283602.555960] iwlwifi 0000:02:00.0: no hotplug settings from platform
>[283602.556017] rtsx_pci 0000:03:00.0: no hotplug settings from platform
>[283602.556066] r8169 0000:04:00.0: no hotplug settings from platform
>[283602.555735] Restarting tasks ... done.
>[283602.557581] video LNXVIDEO:00: Restoring backlight state
>[283602.685568] cfg80211: World regulatory domain updated:
>[283602.685570] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>[283602.685572] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283602.685573] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283602.685574] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>[283602.685574] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283602.685575] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283602.722251] usb 3-1: new high-speed USB device number 38 using xhci_hcd
>[283602.738405] usb 3-1: New USB device found, idVendor=058f, idProduct=6254
>[283602.738410] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
>[283602.738718] hub 3-1:1.0: USB hub found
>[283602.738769] hub 3-1:1.0: 4 ports detected
>[283602.739188] usb 3-3: USB disconnect, device number 20
>[283602.739194] usb 3-3.1: USB disconnect, device number 21
>[283602.894379] usb 3-3.2: USB disconnect, device number 22
>[283602.966916] usb 3-3.3: USB disconnect, device number 23
>[283602.966922] usb 3-3.3.1: USB disconnect, device number 24
>[283603.006986] usb 3-3.3.4: USB disconnect, device number 25
>[283603.094196] usb 3-1.4: new high-speed USB device number 39 using xhci_hcd
>[283603.110464] usb 3-1.4: New USB device found, idVendor=058f, idProduct=6254
>[283603.110472] usb 3-1.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0
>[283603.111029] hub 3-1.4:1.0: USB hub found
>[283603.111118] hub 3-1.4:1.0: 4 ports detected
>[283603.138403] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off
>[283603.410116] usb 3-1.4.1: new low-speed USB device number 40 using xhci_hcd
>[283603.446901] usb 3-1.4.1: New USB device found, idVendor=045e, idProduct=00dd
>[283603.446907] usb 3-1.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
>[283603.446912] usb 3-1.4.1: Product: Comfort Curve Keyboard 2000
>[283603.446915] usb 3-1.4.1: Manufacturer: Microsoft
>[283603.447052] usb 3-1.4.1: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
>[283603.447056] usb 3-1.4.1: ep 0x82 - rounding interval to 64 microframes, ep desc says 80 microframes
>[283603.454824] input: Microsoft Comfort Curve Keyboard 2000 as /devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1.4/3-1.4.1/3-1.4.1:1.0/input/input26
>[283603.454940] hid-generic 0003:045E:00DD.0011: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Comfort Curve Keyboard 2000] on usb-0000:00:14.0-1.4.1/input0
>[283603.466387] input: Microsoft Comfort Curve Keyboard 2000 as /devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1.4/3-1.4.1/3-1.4.1:1.1/input/input27
>[283603.466563] hid-generic 0003:045E:00DD.0012: input,hidraw1: USB HID v1.11 Device [Microsoft Comfort Curve Keyboard 2000] on usb-0000:00:14.0-1.4.1/input1
>[283603.554084] usb 3-1.4.4: new full-speed USB device number 41 using xhci_hcd
>[283603.573018] usb 3-1.4.4: New USB device found, idVendor=046d, idProduct=c52b
>[283603.573025] usb 3-1.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
>[283603.573029] usb 3-1.4.4: Product: USB Receiver
>[283603.573032] usb 3-1.4.4: Manufacturer: Logitech
>[283603.578790] logitech-djreceiver 0003:046D:C52B.0015: hiddev0,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:14.0-1.4.4/input2
>[283618.527744] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[283621.754599] wlan0: authenticate with 00:16:b6:b7:51:89
>[283621.757694] wlan0: send auth to 00:16:b6:b7:51:89 (try 1/3)
>[283621.760603] wlan0: authenticated
>[283621.760728] iwlwifi 0000:02:00.0 wlan0: disabling HT as WMM/QoS is not supported by the AP
>[283621.760731] iwlwifi 0000:02:00.0 wlan0: disabling VHT as WMM/QoS is not supported by the AP
>[283621.764163] wlan0: associate with 00:16:b6:b7:51:89 (try 1/3)
>[283621.766574] wlan0: RX AssocResp from 00:16:b6:b7:51:89 (capab=0x431 status=0 aid=3)
>[283621.772339] wlan0: associated
>[283621.772366] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
>[283621.784813] wlan0: deauthenticating from 00:16:b6:b7:51:89 by local choice (reason=2)
>[283621.796572] wlan0: authenticate with 00:16:b6:b7:51:89
>[283621.798993] wlan0: send auth to 00:16:b6:b7:51:89 (try 1/3)
>[283621.799588] cfg80211: Calling CRDA to update world regulatory domain
>[283621.801727] wlan0: authenticated
>[283621.801962] iwlwifi 0000:02:00.0 wlan0: disabling HT as WMM/QoS is not supported by the AP
>[283621.801968] iwlwifi 0000:02:00.0 wlan0: disabling VHT as WMM/QoS is not supported by the AP
>[283621.804192] wlan0: associate with 00:16:b6:b7:51:89 (try 1/3)
>[283621.805008] cfg80211: World regulatory domain updated:
>[283621.805014] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>[283621.805020] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283621.805025] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283621.805029] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>[283621.805033] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283621.805038] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283621.806711] wlan0: RX AssocResp from 00:16:b6:b7:51:89 (capab=0x431 status=0 aid=3)
>[283621.812398] wlan0: associated
>[283624.232360] input: Logitech Unifying Device. Wireless PID:1025 as /devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1.4/3-1.4.4/3-1.4.4:1.2/0003:046D:C52B.0015/input/input28
>[283624.234351] logitech-djdevice 0003:046D:C52B.0016: input,hidraw3: USB HID v1.11 Mouse [Logitech Unifying Device. Wireless PID:1025] on usb-0000:00:14.0-1.4.4:1
>[283658.348868] wlan0: deauthenticating from 00:16:b6:b7:51:89 by local choice (reason=3)
>[283658.359128] cfg80211: Calling CRDA to update world regulatory domain
>[283658.366248] cfg80211: World regulatory domain updated:
>[283658.366253] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>[283658.366257] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283658.366260] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283658.366263] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>[283658.366265] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283658.366268] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>[283658.463328] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
>[283663.496967] r8169 0000:04:00.0 eth0: link down
>[283663.496997] r8169 0000:04:00.0 eth0: link down
>[283663.497632] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
>[283665.103934] r8169 0000:04:00.0 eth0: link up
>[283665.103948] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
>[285349.147110] usb 3-1.3: new full-speed USB device number 42 using xhci_hcd
>[285349.166235] usb 3-1.3: New USB device found, idVendor=04f9, idProduct=01ea
>[285349.166238] usb 3-1.3: New USB device strings: Mfr=0, Product=0, SerialNumber=3
>[285349.166239] usb 3-1.3: SerialNumber: 000K8N718707
>[285349.178632] usblp 3-1.3:1.0: usblp2: USB Bidirectional printer dev 42 if 0 alt 0 proto 2 vid 0x04F9 pid 0x01EA
>[285349.178653] usbcore: registered new interface driver usblp
>[285432.776551] usblp2: removed
>[285433.280155] usblp 3-1.3:1.0: usblp2: USB Bidirectional printer dev 42 if 0 alt 0 proto 2 vid 0x04F9 pid 0x01EA
>[285433.763541] usblp2: removed
>[285434.767796] usblp 3-1.3:1.0: usblp2: USB Bidirectional printer dev 42 if 0 alt 0 proto 2 vid 0x04F9 pid 0x01EA
>[285527.866750] usblp2: removed
>[285528.620250] usblp 3-1.3:1.0: usblp2: USB Bidirectional printer dev 42 if 0 alt 0 proto 2 vid 0x04F9 pid 0x01EA
>[286648.064113] PM: Syncing filesystems ... done.
>[286648.095239] PM: Preparing system for mem sleep
>[286648.095513] Freezing user space processes ... (elapsed 0.001 seconds) done.
>[286648.097319] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
>[286648.098552] PM: Entering mem sleep
>[286648.098614] Suspending console(s) (use no_console_suspend to debug)
>[286648.098829] sd 0:0:0:0: [sda] Synchronizing SCSI cache
>[286648.099053] sd 0:0:0:0: [sda] Stopping disk
>[286648.100958] option1 ttyUSB1: option_instat_callback: error -2
>[286650.009980] PM: suspend of devices complete after 1911.864 msecs
>[286650.010123] PM: late suspend of devices complete after 0.141 msecs
>[286650.010284] r8169 0000:04:00.0: System wakeup enabled by ACPI
>[286650.041966] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
>[286650.058032] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
>[286650.089841] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
>[286650.105860] PM: noirq suspend of devices complete after 95.765 msecs
>[286650.106063] ACPI: Preparing to enter system sleep state S3
>[286650.106218] PM: Saving platform NVS memory
>[286650.106714] Disabling non-boot CPUs ...
>[286650.107953] kvm: disabling virtualization on CPU1
>[286650.209751] smpboot: CPU 1 is now offline
>[286650.211224] kvm: disabling virtualization on CPU2
>[286650.313712] smpboot: CPU 2 is now offline
>[286650.315125] kvm: disabling virtualization on CPU3
>[286650.417679] smpboot: CPU 3 is now offline
>[286650.418896] ACPI: Low-level resume complete
>[286650.418941] PM: Restoring platform NVS memory
>[286650.419435] Enabling non-boot CPUs ...
>[286650.419468] x86: Booting SMP configuration:
>[286650.419469] smpboot: Booting Node 0 Processor 1 APIC 0x1
>[286650.447533] kvm: enabling virtualization on CPU1
>[286650.449844] CPU1 is up
>[286650.449859] smpboot: Booting Node 0 Processor 2 APIC 0x2
>[286650.461224] kvm: enabling virtualization on CPU2
>[286650.463558] CPU2 is up
>[286650.463574] smpboot: Booting Node 0 Processor 3 APIC 0x3
>[286650.474936] kvm: enabling virtualization on CPU3
>[286650.477251] CPU3 is up
>[286650.480456] ACPI: Waking up from system sleep state S3
>[286650.511135] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
>[286650.543127] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
>[286650.575110] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
>[286650.639178] PM: noirq resume of devices complete after 156.625 msecs
>[286650.639289] PM: early resume of devices complete after 0.091 msecs
>[286650.639515] mei_me 0000:00:16.0: irq 45 for MSI/MSI-X
>[286650.639517] snd_hda_intel 0000:00:1b.0: irq 47 for MSI/MSI-X
>[286650.639581] r8169 0000:04:00.0: System wakeup disabled by ACPI
>[286650.639726] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
>[286650.741281] r8169 0000:04:00.0 eth0: link down
>[286651.026907] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[286651.028823] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[286651.028841] ata1.00: supports DRM functions and may not be fully accessible
>[286651.028898] ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
>[286651.029092] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[286651.029108] ata1.00: supports DRM functions and may not be fully accessible
>[286651.029142] ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
>[286651.029144] ata1.00: configured for UDMA/133
>[286651.034900] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
>[286651.036402] ata5.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[286651.037507] ata5.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[286651.037509] ata5.00: configured for UDMA/133
>[286651.050954] sd 0:0:0:0: [sda] Starting disk
>[286651.055187] PM: resume of devices complete after 416.027 msecs
>[286651.055390] PM: Finishing wakeup.
>[286651.055578] iwlwifi 0000:02:00.0: no hotplug settings from platform
>[286651.055633] rtsx_pci 0000:03:00.0: no hotplug settings from platform
>[286651.055681] r8169 0000:04:00.0: no hotplug settings from platform
>[286651.057071] usb 3-1.3: USB disconnect, device number 42
>[286651.055391] Restarting tasks ... done.
>[286651.057313] video LNXVIDEO:00: Restoring backlight state
>[286651.058709] usblp2: removed
>[286651.902983] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off
>[286652.389872] r8169 0000:04:00.0 eth0: link up
>[290999.533516] [drm] stuck on render ring
>[290999.533521] [drm] GPU crash dump saved to /sys/class/drm/card0/error
>[290999.533523] [drm] GPU hangs can indicate a bug anywhere in the entire gfx stack, including userspace.
>[290999.533524] [drm] Please file a _new_ bug report on bugs.freedesktop.org against DRI -> DRM/Intel
>[290999.533525] [drm] drm/i915 developers can then reassign to the right component if it's not a kernel issue.
>[290999.533526] [drm] The gpu crash dump is required to analyze gpu hangs, so please always attach it.

Actions: View

Attachments on bug 79890: 100827 | 100828 | 100829 | 100830

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.