[Intel-gfx] (no subject)
Ben Gamari
bgamari.foss at gmail.com
Wed Jul 15 08:55:56 CEST 2009
>From Ben Gamari <bgamari.foss at gmail.com> # This line is ignored.
GIT:
From: Ben Gamari <bgamari.foss at gmail.com>
Subject: [PATCH] Error reporting and recovery patchset
In-Reply-To:
Hey all,
Here is the latest version of jbarnes'/oga's/my error reporting and recovery
patch set. Things seem to be quickly stabilizing. I have reworked the reset
code slightly to follow the reset procedures sent out by jbarnes. We also now
flush the request list and wake-up waiting processes during reset. Lastly, I
tried a little bit harder to correctly handle locking (although I just
remembered, I think the hangcheck timer still needs some locking love)
Moreover, I have corrected a few issues with the suspend and resume code in the
first patch. It seems like someone along the line dropped the fence restore
code, which I have replaced. Moreover, we now only save useful interrupt
control registers, leaving alone IIR). In fact, I believe the suspend and
resume fixes here have fixed my issues with suspend and resume with compiz.
At this point, it seems like the suspend and resume patch could certainly be
considered for merging on its own.
All in all, things are looking much better now than they did 24 hours ago. I can
crash the card several times and each time it will come back, albeit with
occassional minor and temporary corruption. Ultimately, however, the reset code
still isn't perfect. After several crashes the machine will eventually
deadlock, as can be seen at the bottom of the dmesg dump below[1].
Unfortunately, this seems to be a pretty tough to track down issue. The lock
debugging code seems to think that multiple (three) processes hold the
dev->struct_mutex lock after the crash. Clearly something is quite wrong here.
Any ideas?
As always, comments are greatly appreciated.
Thanks,
- Ben
[1] dmesg trace taken from machine which crashed after 6 resets
[ 180.664080] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
[ 180.671894] render error detected, EIR: 0x00000000
[ 182.346500] [drm:i915_wait_request] *ERROR* i915_wait_request returns -5 (awaiting 10431 at 10429)
[ 182.355932] GPU hang detected...resetting...i915: checking lock
[ 182.361726] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 182.368276]
[ 182.368277] Showing all locks held in the system:
[ 182.374496] 3 locks held by events/0/9:
[ 182.378416] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 182.386583] #1: (&dev_priv->error_work){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 182.396093] #2: (tasklist_lock){.?.?..}, at: [<ffffffff8108b500>] debug_show_all_locks+0x40/0x1c0
[ 182.405510] 1 lock held by getty/2104:
[ 182.409320] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.418693] 1 lock held by getty/2106:
[ 182.422500] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.431897] 1 lock held by getty/2107:
[ 182.435729] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.445098] 1 lock held by getty/2108:
[ 182.448960] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.458317] 1 lock held by getty/2109:
[ 182.462138] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.471535] 1 lock held by getty/3073:
[ 182.475347] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.484759] 1 lock held by bash/3420:
[ 182.488481] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.497859] 1 lock held by bash/3706:
[ 182.501581] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.510813] 1 lock held by getty/3842:
[ 182.514586] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 182.523747]
[ 182.525267] =============================================
[ 182.525269]
[ 182.532165] i915: Aquiring mutex
[ 182.540008] i915: Waking up sleepers
[ 182.543613] i915: Retiring requests
[ 182.547114] i915: Saving display config
[ 182.551068] i915: Waiting for hardware to recover
[ 182.555875] i915: Stop ring
[ 182.558676] i915: Init ring
[ 182.561511] i915: irq_uninstall
[ 182.564673] [drm:i915_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
[ 182.573467] i915: irq_install
[ 182.576509] i915: Restore display
[ 182.579992] done.
[ 187.340174] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
[ 187.347955] render error detected, EIR: 0x00000000
[ 187.349223] [drm:i915_wait_request] *ERROR* i915_wait_request returns -5 (awaiting 10455 at 10453)
[ 187.352363] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.368294] GPU hang detected...resetting...
[ 187.371955] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.378887] i915: checking lock
[ 187.380957] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.382573] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.383418] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.384329] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.384575] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.385032] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.419014]
[ 187.419014] Showing all locks held in the system:
[ 187.425335] 3 locks held by events/0/9:
[ 187.429202] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 187.437260] #1: (&dev_priv->error_work){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 187.446638] #2: (tasklist_lock){.?.?..}, at: [<ffffffff8108b500>] debug_show_all_locks+0x40/0x1c0
[ 187.455858] 1 lock held by getty/2104:
[ 187.459626] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 187.468822] 1 lock held by getty/2106:
[ 187.472639] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 187.481807] 1 lock held by getty/2107:
[ 187.485627] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 187.494785] 1 lock held by getty/2108:
[ 187.498283] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.498406] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
...
[ 187.518137] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 187.520392] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 188.182390] psmouse.c: DualPoint TouchPad at isa0060/serio1/input0 lost synchronization, throwing 1 bytes away.
[ 188.193008] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 188.202150] 1 lock held by getty/2109:
[ 188.205908] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 188.215060] 1 lock held by getty/3073:
[ 188.218818] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 188.227955] 1 lock held by bash/3420:
[ 188.231631] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 188.240843] 1 lock held by bash/3706:
[ 188.244515] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 188.253648] 1 lock held by getty/3842:
[ 188.257408] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 188.266535]
[ 188.268044] =============================================
[ 188.268047]
[ 188.274937] i915: Aquiring mutex
[ 188.285692] i915: Waking up sleepers
[ 188.289289] i915: Retiring requests
[ 188.292777] i915: Saving display config
[ 188.296701] i915: Waiting for hardware to recover
[ 188.301499] i915: Stop ring
[ 188.304293] i915: Init ring
[ 188.307078] i915: irq_uninstall
[ 188.311359] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 188.320696] [drm:i915_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
[ 188.322900] [drm:i915_gem_execbuffer] *ERROR* Execbuf while wedged
[ 188.335665] i915: irq_install
[ 188.339081] i915: Restore display
[ 188.342630] done.
[ 189.044536] psmouse.c: resync failed, issuing reconnect request
[ 195.997073] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
[ 196.004850] render error detected, EIR: 0x00000000
[ 196.011209] GPU hang detected...resetting...i915: checking lock
[ 196.017382] [drm:i965_reset] *ERROR* Killing process 2786 (X) for holding i915 device mutex
[ 196.025763]
[ 196.025765] Showing all locks held in the system:
[ 196.032008] 3 locks held by events/1/10:
[ 196.035946] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 196.044040] #1: (&dev_priv->error_work){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 196.053374] #2: (tasklist_lock){.?.?..}, at: [<ffffffff8108b500>] debug_show_all_locks+0x40/0x1c0
[ 196.062574] 1 lock held by getty/2104:
[ 196.066337] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.075473] 1 lock held by getty/2106:
[ 196.079236] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.088379] 1 lock held by getty/2107:
[ 196.092141] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.101288] 1 lock held by getty/2108:
[ 196.105045] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.114176] 1 lock held by getty/2109:
[ 196.117933] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.127175] 1 lock held by getty/3073:
[ 196.130933] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.140074] 1 lock held by bash/3420:
[ 196.143742] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.152882] 1 lock held by bash/3706:
[ 196.156555] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.165698] 1 lock held by getty/3842:
[ 196.169455] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 196.178582]
[ 196.180073] =============================================
[ 196.180076]
[ 196.186970] i915: Aquiring mutex
[ 196.190214] [drm:i915_wait_request] *ERROR* i915_wait_request returns -5 (awaiting 11222 at 11220)
[ 196.200732] i915: Waking up sleepers
[ 196.204322] i915: Retiring requests
[ 196.207817] i915: Saving display config
[ 196.211765] i915: Waiting for hardware to recover
[ 196.216573] i915: Stop ring
[ 196.219374] i915: Init ring
[ 196.222248] i915: irq_uninstall
[ 196.225572] [drm:i915_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
[ 196.234380] i915: irq_install
[ 196.237664] i915: Restore display
[ 196.241141] done.
[ 197.010692] [drm] TV-16: set mode NTSC 480i 0
[ 197.182967] [drm] TV-16: set mode NTSC 480i 0
[ 197.506447] [drm] TV-16: set mode NTSC 480i 0
[ 197.676009] [drm] TV-16: set mode NTSC 480i 0
[ 198.912196] [drm] TV-16: set mode NTSC 480i 0
[ 199.081393] [drm] TV-16: set mode NTSC 480i 0
[ 199.405882] [drm] TV-16: set mode NTSC 480i 0
[ 199.576507] [drm] TV-16: set mode NTSC 480i 0
[ 199.904602] [drm] TV-16: set mode NTSC 480i 0
[ 200.074198] [drm] TV-16: set mode NTSC 480i 0
[ 205.912074] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
[ 205.919836] render error detected, EIR: 0x00000000
[ 205.926170] GPU hang detected...resetting...i915: checking lock
[ 205.932149]
[ 205.932151] Showing all locks held in the system:
[ 205.938364] 3 locks held by events/1/10:
[ 205.942304] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 205.950424] #1: (&dev_priv->error_work){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 205.959751] #2: (tasklist_lock){.?.?..}, at: [<ffffffff8108b500>] debug_show_all_locks+0x40/0x1c0
[ 205.968930] 1 lock held by getty/2104:
[ 205.972694] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 205.981870] 1 lock held by getty/2106:
[ 205.985645] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 205.994798] 1 lock held by getty/2107:
[ 205.999067] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 206.009289] 1 lock held by getty/2108:
[ 206.012469] [drm:i915_wait_request] *ERROR* i915_wait_request returns -5 (awaiting 11863 at 11863)
[ 206.021994] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 206.031147] 1 lock held by getty/2109:
[ 206.034914] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 206.044082] 1 lock held by getty/3073:
[ 206.047934] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 206.057117] 1 lock held by getty/3842:
[ 206.060862] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 206.070044]
[ 206.071536] =============================================
[ 206.071539]
[ 206.078438] i915: Aquiring mutex
[ 206.081718] i915: Waking up sleepers
[ 206.085327] i915: Retiring requests
[ 206.088862] i915: Saving display config
[ 206.092841] i915: Waiting for hardware to recover
[ 206.097665] i915: Stop ring
[ 206.100451] i915: Init ring
[ 206.103265] i915: irq_uninstall
[ 206.106461] [drm:i915_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0
[ 206.115255] i915: irq_install
[ 206.118272] i915: Restore display
[ 206.121767] done.
[ 220.102852] [drm] TV-16: set mode NTSC 480i 0
[ 220.107614] hda-intel: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
[ 220.274195] [drm] TV-16: set mode NTSC 480i 0
[ 220.598115] [drm] TV-16: set mode NTSC 480i 0
[ 220.769241] [drm] TV-16: set mode NTSC 480i 0
[ 221.095630] [drm] TV-16: set mode NTSC 480i 0
[ 221.266591] [drm] TV-16: set mode NTSC 480i 0
[ 222.141381] [drm] TV-16: set mode NTSC 480i 0
[ 222.311323] [drm] TV-16: set mode NTSC 480i 0
[ 228.321427] wlan0: authenticate with AP 00:40:10:10:00:03
[ 228.328838] wlan0: authenticated
[ 228.332119] wlan0: associate with AP 00:40:10:10:00:03
[ 228.343610] wlan0: RX AssocResp from 00:40:10:10:00:03 (capab=0x411 status=0 aid=3)
[ 228.351307] wlan0: associated
[ 238.772147] [drm:i915_hangcheck_elapsed] *ERROR* Hangcheck timer elapsed... GPU hung
[ 238.779914] render error detected, EIR: 0x00000000
[ 292.456123] SysRq : Show Blocked State
[ 292.459887] task PC stack pid father
[ 292.459986] events/0 D 0000000000000246 0 9 2 0x00000000
[ 292.459986] ffff88011baebd10 0000000000000046 ffff880028061d98 0000000000000002
[ 292.459986] 0000000000000002 ffff88011bae4790 ffff88011bae4000 ffffffff81518b60
[ 292.459986] 0000000000000000 ffff88011bae4000 ffff88011bae43b0 000000000000fa08
[ 292.459986] Call Trace:
[ 292.459986] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 292.459986] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 292.459986] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 292.459986] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 292.459986] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 292.459986] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 292.459986] [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 292.459986] [<ffffffffa006fa30>] ? i915_gem_retire_work_handler+0x0/0x90 [i915]
[ 292.548473] [<ffffffff810734d0>] worker_thread+0x1d0/0x380
[ 292.548473] [<ffffffff8107347e>] ? worker_thread+0x17e/0x380
[ 292.548473] [<ffffffff81048f98>] ? complete+0x28/0x60
[ 292.548473] [<ffffffff81518bb0>] ? _spin_unlock_irqrestore+0x40/0x70
[ 292.548473] [<ffffffff81078e30>] ? autoremove_wake_function+0x0/0x40
[ 292.548473] [<ffffffff81073300>] ? worker_thread+0x0/0x380
[ 292.548473] [<ffffffff81078a4e>] kthread+0x9e/0xb0
[ 292.548473] [<ffffffff8101326a>] child_rip+0xa/0x20
[ 292.548473] [<ffffffff81012bd0>] ? restore_args+0x0/0x30
[ 292.548473] [<ffffffff810789b0>] ? kthread+0x0/0xb0
[ 292.548473] [<ffffffff81013260>] ? child_rip+0x0/0x20
[ 292.548473] X D 0000000000000246 0 4100 4094 0x00400004
[ 292.548473] ffff880116929c48 0000000000000046 ffff880116929bb8 0000000000000246
[ 292.548473] 0000000000000000 ffff8800db2b4740 0000000000000000 ffff880114937928
[ 292.548473] ffff880116929bc8 ffff8800db2b4000 ffff8800db2b43b0 000000000000fa08
[ 292.548473] Call Trace:
[ 292.548473] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 292.548473] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 292.548473] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 292.548473] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 292.548473] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 292.548473] [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 292.548473] [<ffffffffa0034db5>] drm_ioctl+0x185/0x3b0 [drm]
[ 292.548473] [<ffffffff8107c7d1>] ? lock_hrtimer_base+0x31/0x60
[ 292.548473] [<ffffffff8113cd2c>] vfs_ioctl+0x7c/0xa0
[ 292.548473] [<ffffffff8113ce7a>] do_vfs_ioctl+0x8a/0x5a0
[ 292.548473] [<ffffffff81062b71>] ? do_setitimer+0x2f1/0x380
[ 292.548473] [<ffffffff810120aa>] ? sysret_check+0x2e/0x69
[ 292.548473] [<ffffffff8113d411>] sys_ioctl+0x81/0xa0
[ 292.548473] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 292.548473] mission-contr D 0000000000000002 0 4473 1 0x00000000
[ 292.548473] ffff8800cf035ce8 0000000000000046 ffff8800cf035c68 ffffffff81048afb
[ 292.548473] ffffffff81048a82 0000000000000000 0000000000000184 ffff8800d54a6000
[ 292.548473] 0000000000000000 0000000000000000 ffff8800d54a63b0 000000000000fa08
[ 292.548473] Call Trace:
[ 292.548473] [<ffffffff81048afb>] ? cpuacct_charge+0x9b/0xb0
[ 292.548473] [<ffffffff81048a82>] ? cpuacct_charge+0x22/0xb0
[ 292.548473] [<ffffffff81516a75>] schedule_timeout+0x1e5/0x240
[ 292.548473] [<ffffffff8151665b>] ? wait_for_common+0x3b/0x160
[ 292.548473] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 292.548473] [<ffffffff8108cbfd>] ? trace_hardirqs_on_caller+0x14d/0x190
[ 292.548473] [<ffffffff815166f1>] wait_for_common+0xd1/0x160
[ 292.548473] [<ffffffff81052220>] ? default_wake_function+0x0/0x20
[ 292.548473] [<ffffffff8108cbfd>] ? trace_hardirqs_on_caller+0x14d/0x190
[ 292.548473] [<ffffffff810f4690>] ? lru_add_drain_per_cpu+0x0/0x10
[ 292.548473] [<ffffffff8151683d>] wait_for_completion+0x1d/0x20
[ 292.548473] [<ffffffff81073e93>] flush_work+0xb3/0x100
[ 292.548473] [<ffffffff81073e0a>] ? flush_work+0x2a/0x100
[ 292.548473] [<ffffffff81073d30>] ? wq_barrier_func+0x0/0x20
[ 292.548473] [<ffffffff81074fc3>] schedule_on_each_cpu+0xe3/0x120
[ 292.548473] [<ffffffff810f3d25>] lru_add_drain_all+0x15/0x20
[ 292.548473] [<ffffffff81109075>] sys_mlock+0x45/0x100
[ 292.548473] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 292.548473] Sched Debug Version: v0.09, 2.6.31-rc2-work #37
[ 292.548473] now at 292863.444793 msecs
[ 292.548473] .jiffies : 4294965441
[ 292.548473] .sysctl_sched_latency : 40.000000
[ 292.548473] .sysctl_sched_min_granularity : 8.000000
[ 292.548473] .sysctl_sched_wakeup_granularity : 10.000000
[ 292.548473] .sysctl_sched_child_runs_first : 0.000001
[ 292.548473] .sysctl_sched_features : 113917
[ 292.548473]
[ 292.548473] cpu#0, 2194.656 MHz
[ 292.548473] .nr_running : 1
[ 292.548473] .load : 1024
[ 292.548473] .nr_switches : 266827
[ 292.548473] .nr_load_updates : 29945
[ 292.548473] .nr_uninterruptible : -46
[ 292.548473] .next_balance : 4294.965409
[ 292.548473] .curr->pid : 0
[ 292.548473] .clock : 292548.473227
[ 292.548473] .cpu_load[0] : 0
[ 292.548473] .cpu_load[1] : 0
[ 292.548473] .cpu_load[2] : 2
[ 292.548473] .cpu_load[3] : 26
[ 292.548473] .cpu_load[4] : 130
[ 292.548473] .yld_count : 8528
[ 292.548473] .sched_switch : 0
[ 292.548473] .sched_count : 278022
[ 292.548473] .sched_goidle : 76922
[ 292.548473] .ttwu_count : 145179
[ 292.548473] .ttwu_local : 124513
[ 292.548473] .bkl_count : 1990
[ 292.548473]
[ 292.548473] cfs_rq[0]:/
[ 292.548473] .exec_clock : 43301.029298
[ 292.548473] .MIN_vruntime : 110019.594831
[ 292.548473] .min_vruntime : 110059.547242
[ 292.548473] .max_vruntime : 110019.594831
[ 292.548473] .spread : 0.000000
[ 292.548473] .spread0 : 0.000000
[ 292.548473] .nr_running : 1
[ 292.548473] .load : 1024
[ 292.548473] .nr_spread_over : 18
[ 292.548473] .shares : 0
[ 292.548473]
[ 292.548473] rt_rq[0]:/
[ 292.548473] .rt_nr_running : 0
[ 292.548473] .rt_throttled : 0
[ 292.548473] .rt_time : 0.000000
[ 292.548473] .rt_runtime : 950.000000
[ 292.548473]
[ 292.548473] runnable tasks:
[ 292.548473] task PID tree-key switches prio exec-runtime sum-exec sum-sleep
[ 292.548473] ----------------------------------------------------------------------------------------------------------
[ 292.548473] hald-addon-inpu 2708 110019.594831 544 120 110019.594831 37.796431 266222.905750 /
[ 292.548473]
[ 292.548473] cpu#1, 2194.656 MHz
[ 292.548473] .nr_running : 0
[ 292.548473] .load : 0
[ 292.548473] .nr_switches : 302574
[ 292.548473] .nr_load_updates : 31664
[ 292.548473] .nr_uninterruptible : 49
[ 292.548473] .next_balance : 4294.965449
[ 292.548473] .curr->pid : 0
[ 292.548473] .clock : 292583.547930
[ 292.548473] .cpu_load[0] : 0
[ 292.548473] .cpu_load[1] : 1236
[ 292.548473] .cpu_load[2] : 1362
[ 292.548473] .cpu_load[3] : 1268
[ 292.548473] .cpu_load[4] : 1106
[ 292.548473] .yld_count : 8468
[ 292.548473] .sched_switch : 0
[ 292.548473] .sched_count : 313446
[ 292.548473] .sched_goidle : 87020
[ 292.548473] .ttwu_count : 162402
[ 292.548473] .ttwu_local : 144238
[ 292.548473] .bkl_count : 1728
[ 292.548473]
[ 292.548473] cfs_rq[1]:/
[ 292.548473] .exec_clock : 43493.129791
[ 292.548473] .MIN_vruntime : 0.000001
[ 292.548473] .min_vruntime : 90163.135570
[ 292.548473] .max_vruntime : 0.000001
[ 292.548473] .spread : 0.000000
[ 292.548473] .spread0 : -19896.411672
[ 292.548473] .nr_running : 0
[ 292.548473] .load : 0
[ 292.548473] .nr_spread_over : 10
[ 292.548473] .shares : 0
[ 292.548473]
[ 292.548473] rt_rq[1]:/
[ 292.548473] .rt_nr_running : 0
[ 292.548473] .rt_throttled : 0
[ 292.548473] .rt_time : 0.000000
[ 293.298715] .rt_runtime : 950.000000
[ 293.298715]
[ 293.298715] runnable tasks:
[ 293.298715] task PID tree-key switches prio exec-runtime sum-exec sum-sleep
[ 293.298715] ----------------------------------------------------------------------------------------------------------
[ 293.298715]
[ 361.321101] INFO: task events/0:9 blocked for more than 120 seconds.
[ 361.327485] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.335345] events/0 D 0000000000000246 0 9 2 0x00000000
[ 361.342302] ffff88011baebd10 0000000000000046 ffff880028061d98 0000000000000002
[ 361.349808] 0000000000000002 ffff88011bae4790 ffff88011bae4000 ffffffff81518b60
[ 361.357314] 0000000000000000 ffff88011bae4000 ffff88011bae43b0 000000000000fa08
[ 361.364834] Call Trace:
[ 361.367319] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 361.373174] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 361.379458] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 361.385613] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 361.393136] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 361.400643] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 361.406501] [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 361.413850] [<ffffffffa006fa30>] ? i915_gem_retire_work_handler+0x0/0x90 [i915]
[ 361.421268] [<ffffffff810734d0>] worker_thread+0x1d0/0x380
[ 361.426857] [<ffffffff8107347e>] ? worker_thread+0x17e/0x380
[ 361.432626] [<ffffffff81048f98>] ? complete+0x28/0x60
[ 361.437782] [<ffffffff81518bb0>] ? _spin_unlock_irqrestore+0x40/0x70
[ 361.444246] [<ffffffff81078e30>] ? autoremove_wake_function+0x0/0x40
[ 361.450704] [<ffffffff81073300>] ? worker_thread+0x0/0x380
[ 361.456300] [<ffffffff81078a4e>] kthread+0x9e/0xb0
[ 361.461207] [<ffffffff8101326a>] child_rip+0xa/0x20
[ 361.466238] [<ffffffff81012bd0>] ? restore_args+0x0/0x30
[ 361.471662] [<ffffffff810789b0>] ? kthread+0x0/0xb0
[ 361.476643] [<ffffffff81013260>] ? child_rip+0x0/0x20
[ 361.481805] 3 locks held by events/0/9:
[ 361.485658] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 361.493707] #1: (&(&dev_priv->mm.retire_work)->work){+.+...}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 361.504170] #2: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i9
[ 361.515035] INFO: task X:4100 blocked for more than 120 seconds.
[ 361.521051] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.528891] X D 0000000000000246 0 4100 4094 0x00400004
[ 361.535829] ffff880116929c48 0000000000000046 ffff880116929bb8 0000000000000246
[ 361.543336] 0000000000000000 ffff8800db2b4740 0000000000000000 ffff880114937928
[ 361.550839] ffff880116929bc8 ffff8800db2b4000 ffff8800db2b43b0 000000000000fa08
[ 361.558352] Call Trace:
[ 361.560800] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 361.567127] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 361.573317] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 361.580409] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 361.587475] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 361.593265] [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 361.600193] [<ffffffffa0034db5>] drm_ioctl+0x185/0x3b0 [drm]
[ 361.605970] [<ffffffff8107c7d1>] ? lock_hrtimer_base+0x31/0x60
[ 361.611943] [<ffffffff8113cd2c>] vfs_ioctl+0x7c/0xa0
[ 361.617033] [<ffffffff8113ce7a>] do_vfs_ioctl+0x8a/0x5a0
[ 361.622482] [<ffffffff81062b71>] ? do_setitimer+0x2f1/0x380
[ 361.628168] [<ffffffff810120aa>] ? sysret_check+0x2e/0x69
[ 361.633703] [<ffffffff8113d411>] sys_ioctl+0x81/0xa0
[ 361.638775] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 361.644806] 1 lock held by X/4100:
[ 361.648231] #0: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 362.243247] SysRq : Show Locks Held
[ 362.246411]
[ 362.246411] Showing all locks held in the system:
[ 362.246411] 3 locks held by events/0/9:
[ 362.246411] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 362.246411] #1: (&(&dev_priv->mm.retire_work)->work){+.+...}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 362.246411] #2: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i9
[ 362.283044] 1 lock held by getty/2104:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by getty/2106:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by getty/2107:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by getty/2108:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by getty/2109:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by getty/3073:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by getty/3842:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by X/4100:
[ 362.283044] #0: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 362.283044] 1 lock held by compiz.real/4399:
[ 362.283044] #0: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa0070c9c>] i915_gem_set_domain_ioctl+0x9c/0xf0 [i915]
[ 362.283044] 5 locks held by tracker-indexer/4443:
[ 362.283044] #0: (&serio->lock){-.-...}, at: [<ffffffff813bb5c6>] serio_interrupt+0x36/0xa0
[ 362.283044] #1: (&dev->event_lock){-.-...}, at: [<ffffffff813bff42>] input_event+0x62/0xa0
[ 362.283044] #2: (rcu_read_lock){.+.+..}, at: [<ffffffff813be500>] input_pass_event+0x0/0xf0
[ 362.283044] #3: (sysrq_key_table_lock){-.....}, at: [<ffffffff813166f5>] __handle_sysrq+0x35/0x190
[ 362.283044] #4: (tasklist_lock){.?.?..}, at: [<ffffffff8108b500>] debug_show_all_locks+0x40/0x1c0
[ 362.283044] 1 lock held by bash/4452:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044] 1 lock held by bash/4707:
[ 362.283044] #0: (&tty->atomic_read_lock){+.+...}, at: [<ffffffff8130224e>] n_tty_read+0x3be/0x950
[ 362.283044]
[ 362.283044] =============================================
[ 362.283044]
[ 481.657281] INFO: task events/0:9 blocked for more than 120 seconds.
[ 481.663663] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 481.671518] events/0 D 0000000000000246 0 9 2 0x00000000
[ 481.678468] ffff88011baebd10 0000000000000046 ffff880028061d98 0000000000000002
[ 481.686061] 0000000000000002 ffff88011bae4790 ffff88011bae4000 ffffffff81518b60
[ 481.693556] 0000000000000000 ffff88011bae4000 ffff88011bae43b0 000000000000fa08
[ 481.701051] Call Trace:
[ 481.703506] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 481.709362] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 481.715639] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 481.721786] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 481.729302] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 481.736796] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 481.742573] [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 481.749911] [<ffffffffa006fa30>] ? i915_gem_retire_work_handler+0x0/0x90 [i915]
[ 481.757323] [<ffffffff810734d0>] worker_thread+0x1d0/0x380
[ 481.762908] [<ffffffff8107347e>] ? worker_thread+0x17e/0x380
[ 481.768672] [<ffffffff81048f98>] ? complete+0x28/0x60
[ 481.773823] [<ffffffff81518bb0>] ? _spin_unlock_irqrestore+0x40/0x70
[ 481.780279] [<ffffffff81078e30>] ? autoremove_wake_function+0x0/0x40
[ 481.786748] [<ffffffff81073300>] ? worker_thread+0x0/0x380
[ 481.792337] [<ffffffff81078a4e>] kthread+0x9e/0xb0
[ 481.797229] [<ffffffff8101326a>] child_rip+0xa/0x20
[ 481.802213] [<ffffffff81012bd0>] ? restore_args+0x0/0x30
[ 481.807636] [<ffffffff810789b0>] ? kthread+0x0/0xb0
[ 481.812619] [<ffffffff81013260>] ? child_rip+0x0/0x20
[ 481.817769] 3 locks held by events/0/9:
[ 481.821620] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 481.829638] #1: (&(&dev_priv->mm.retire_work)->work){+.+...}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 481.840082] #2: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 481.850914] INFO: task X:4100 blocked for more than 120 seconds.
[ 481.856930] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 481.864767] X D 0000000000000246 0 4100 4094 0x00400004
[ 481.871697] ffff880116929c48 0000000000000046 ffff880116929bb8 0000000000000246
[ 481.879190] 0000000000000000 ffff8800db2b4740 0000000000000000 ffff880114937928
[ 481.886750] ffff880116929bc8 ffff8800db2b4000 ffff8800db2b43b0 000000000000fa08
[ 481.894247] Call Trace:
[ 481.896697] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 481.902977] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 481.909101] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 481.916157] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 481.923209] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 481.928989] [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 481.935917] [<ffffffffa0034db5>] drm_ioctl+0x185/0x3b0 [drm]
[ 481.941685] [<ffffffff8107c7d1>] ? lock_hrtimer_base+0x31/0x60
[ 481.947623] [<ffffffff8113cd2c>] vfs_ioctl+0x7c/0xa0
[ 481.952700] [<ffffffff8113ce7a>] do_vfs_ioctl+0x8a/0x5a0
[ 481.958126] [<ffffffff81062b71>] ? do_setitimer+0x2f1/0x380
[ 481.963805] [<ffffffff810120aa>] ? sysret_check+0x2e/0x69
[ 481.969313] [<ffffffff8113d411>] sys_ioctl+0x81/0xa0
[ 481.974392] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 481.980420] 1 lock held by X/4100:
[ 481.983837] #0: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 481.994320] INFO: task mission-control:4473 blocked for more than 120 seconds.
[ 482.001654] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 482.009525] mission-contr D 0000000000000002 0 4473 1 0x00000000
[ 482.016563] ffff8800cf035ce8 0000000000000046 ffff8800cf035c68 ffffffff81048afb
[ 482.024074] ffffffff81048a82 0000000000000000 0000000000000184 ffff8800d54a6000
[ 482.031583] 0000000000000000 0000000000000000 ffff8800d54a63b0 000000000000fa08
[ 482.039087] Call Trace:
[ 482.041568] [<ffffffff81048afb>] ? cpuacct_charge+0x9b/0xb0
[ 482.047245] [<ffffffff81048a82>] ? cpuacct_charge+0x22/0xb0
[ 482.052930] [<ffffffff81516a75>] schedule_timeout+0x1e5/0x240
[ 482.058778] [<ffffffff8151665b>] ? wait_for_common+0x3b/0x160
[ 482.064634] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 482.070495] [<ffffffff8108cbfd>] ? trace_hardirqs_on_caller+0x14d/0x190
[ 482.077214] [<ffffffff815166f1>] wait_for_common+0xd1/0x160
[ 482.082893] [<ffffffff81052220>] ? default_wake_function+0x0/0x20
[ 482.089100] [<ffffffff8108cbfd>] ? trace_hardirqs_on_caller+0x14d/0x190
[ 482.095874] [<ffffffff810f4690>] ? lru_add_drain_per_cpu+0x0/0x10
[ 482.102083] [<ffffffff8151683d>] wait_for_completion+0x1d/0x20
[ 482.108281] [<ffffffff81073e93>] flush_work+0xb3/0x100
[ 482.113533] [<ffffffff81073e0a>] ? flush_work+0x2a/0x100
[ 482.118943] [<ffffffff81073d30>] ? wq_barrier_func+0x0/0x20
[ 482.124602] [<ffffffff81074fc3>] schedule_on_each_cpu+0xe3/0x120
[ 482.130727] [<ffffffff810f3d25>] lru_add_drain_all+0x15/0x20
[ 482.136487] [<ffffffff81109075>] sys_mlock+0x45/0x100
[ 482.141635] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 482.147650] no locks held by mission-control/4473.
[ 602.153047] INFO: task events/0:9 blocked for more than 120 seconds.
[ 602.159409] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 602.167234] events/0 D 0000000000000246 0 9 2 0x00000000
[ 602.174155] ffff88011baebd10 0000000000000046 ffff880028061d98 0000000000000002
[ 602.181618] 0000000000000002 ffff88011bae4790 ffff88011bae4000 ffffffff81518b60
[ 602.189101] 0000000000000000 ffff88011bae4000 ffff88011bae43b0 000000000000fa08
[ 602.196562] Call Trace:
[ 602.199027] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 602.204877] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 602.211207] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 602.217324] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 602.224821] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 602.232318] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 602.238076] [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 602.245386] [<ffffffffa006fa30>] ? i915_gem_retire_work_handler+0x0/0x90 [i915]
[ 602.252791] [<ffffffff810734d0>] worker_thread+0x1d0/0x380
[ 602.258363] [<ffffffff8107347e>] ? worker_thread+0x17e/0x380
[ 602.264119] [<ffffffff81048f98>] ? complete+0x28/0x60
[ 602.269274] [<ffffffff81518bb0>] ? _spin_unlock_irqrestore+0x40/0x70
[ 602.275726] [<ffffffff81078e30>] ? autoremove_wake_function+0x0/0x40
[ 602.282185] [<ffffffff81073300>] ? worker_thread+0x0/0x380
[ 602.287756] [<ffffffff81078a4e>] kthread+0x9e/0xb0
[ 602.292654] [<ffffffff8101326a>] child_rip+0xa/0x20
[ 602.297628] [<ffffffff81012bd0>] ? restore_args+0x0/0x30
[ 602.303032] [<ffffffff810789b0>] ? kthread+0x0/0xb0
[ 602.307993] [<ffffffff81013260>] ? child_rip+0x0/0x20
[ 602.313214] 3 locks held by events/0/9:
[ 602.317055] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 602.325055] #1: (&(&dev_priv->mm.retire_work)->work){+.+...}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 602.335621] #2: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 602.346421] INFO: task X:4100 blocked for more than 120 seconds.
[ 602.352440] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 602.360259] X D 0000000000000246 0 4100 4094 0x00400004
[ 602.367168] ffff880116929c48 0000000000000046 ffff880116929bb8 0000000000000246
[ 602.374623] 0000000000000000 ffff8800db2b4740 0000000000000000 ffff880114937928
[ 602.382101] ffff880116929bc8 ffff8800db2b4000 ffff8800db2b43b0 000000000000fa08
[ 602.389581] Call Trace:
[ 602.392087] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 602.398371] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 602.404480] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 602.411606] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 602.418814] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 602.424620] [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 602.431501] [<ffffffffa0034db5>] drm_ioctl+0x185/0x3b0 [drm]
[ 602.437246] [<ffffffff8107c7d1>] ? lock_hrtimer_base+0x31/0x60
[ 602.443167] [<ffffffff8113cd2c>] vfs_ioctl+0x7c/0xa0
[ 602.448326] [<ffffffff8113ce7a>] do_vfs_ioctl+0x8a/0x5a0
[ 602.453783] [<ffffffff81062b71>] ? do_setitimer+0x2f1/0x380
[ 602.459461] [<ffffffff810120aa>] ? sysret_check+0x2e/0x69
[ 602.464945] [<ffffffff8113d411>] sys_ioctl+0x81/0xa0
[ 602.469997] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 602.476006] 1 lock held by X/4100:
[ 602.479409] #0: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 602.489756] INFO: task mission-control:4473 blocked for more than 120 seconds.
[ 602.497060] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 602.504880] mission-contr D 0000000000000002 0 4473 1 0x00000000
[ 602.511796] ffff8800cf035ce8 0000000000000046 ffff8800cf035c68 ffffffff81048afb
[ 602.519298] ffffffff81048a82 0000000000000000 0000000000000184 ffff8800d54a6000
[ 602.526873] 0000000000000000 0000000000000000 ffff8800d54a63b0 000000000000fa08
[ 602.534348] Call Trace:
[ 602.536813] [<ffffffff81048afb>] ? cpuacct_charge+0x9b/0xb0
[ 602.542480] [<ffffffff81048a82>] ? cpuacct_charge+0x22/0xb0
[ 602.548154] [<ffffffff81516a75>] schedule_timeout+0x1e5/0x240
[ 602.553984] [<ffffffff8151665b>] ? wait_for_common+0x3b/0x160
[ 602.559827] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 602.565659] [<ffffffff8108cbfd>] ? trace_hardirqs_on_caller+0x14d/0x190
[ 602.572356] [<ffffffff815166f1>] wait_for_common+0xd1/0x160
[ 602.578026] [<ffffffff81052220>] ? default_wake_function+0x0/0x20
[ 602.584201] [<ffffffff8108cbfd>] ? trace_hardirqs_on_caller+0x14d/0x190
[ 602.590910] [<ffffffff810f4690>] ? lru_add_drain_per_cpu+0x0/0x10
[ 602.597086] [<ffffffff8151683d>] wait_for_completion+0x1d/0x20
[ 602.603004] [<ffffffff81073e93>] flush_work+0xb3/0x100
[ 602.608244] [<ffffffff81073e0a>] ? flush_work+0x2a/0x100
[ 602.613749] [<ffffffff81073d30>] ? wq_barrier_func+0x0/0x20
[ 602.619481] [<ffffffff81074fc3>] schedule_on_each_cpu+0xe3/0x120
[ 602.625575] [<ffffffff810f3d25>] lru_add_drain_all+0x15/0x20
[ 602.631323] [<ffffffff81109075>] sys_mlock+0x45/0x100
[ 602.636464] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 602.642472] no locks held by mission-control/4473.
[ 627.641459] SysRq : HELP : loglevel(0-9) reBoot Crash show-all-locks(D) terminate-all-tasks(E) memory-full-oom-kill(F) kill-all-tasks(I) thaw-filesystems(J) saK show-ba
[ 632.958898] SysRq : Show backtrace of all active CPUs
[ 632.962820] CPU1:
[ 632.962820] CPU 1:
[ 632.962820] Modules linked in: isofs udf crc_itu_t cryptd aes_x86_64 aes_generic binfmt_misc snd_hda_codec_idt snd_hda_intel snd_hda_codec snd_pcm_oss snd_mixer_oss sndp
[ 632.962820] Pid: 0, comm: swapper Not tainted 2.6.31-rc2-work #37 Latitude D830
[ 632.962820] RIP: 0010:[<ffffffff812e4d68>] [<ffffffff812e4d68>] acpi_idle_enter_bm+0x267/0x29b
[ 632.962820] RSP: 0018:ffff88011bae9e88 EFLAGS: 00000202
[ 632.962820] RAX: 00000000004c38b3 RBX: ffff88011bae9ed8 RCX: 0000000000000000
[ 632.962820] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffff88011bae0000
[ 632.962820] RBP: ffffffff81012b0e R08: 0000000000000000 R09: 0000000000000001
[ 632.962820] R10: 0000000000000000 R11: 0000000000000001 R12: ffffffff8108260f
[ 632.962820] R13: ffff88011bae9e08 R14: 00000000ffffffff R15: ffff88011b1ae00c
[ 632.962820] FS: 0000000000000000(0000) GS:ffff88002806a000(0000) knlGS:0000000000000000
[ 632.962820] CS: 0010 DS: 0018 ES: 0018 CR0: 000000008005003b
[ 632.962820] CR2: 00000000023c71c0 CR3: 0000000001001000 CR4: 00000000000006e0
[ 632.962820] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 632.962820] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[ 632.962820] Call Trace:
[ 632.962820] [<ffffffff812e4d61>] ? acpi_idle_enter_bm+0x260/0x29b
[ 632.962820] [<ffffffff813f6743>] ? cpuidle_idle_call+0xa3/0xf0
[ 632.962820] [<ffffffff81010e5f>] ? cpu_idle+0xbf/0x110
[ 632.962820] [<ffffffff81511cf5>] ? start_secondary+0x19d/0x1a2
[ 633.141529] CPU0:
[ 633.143458] ffff88002804fef0 ffff88002804ff08 0000000000000086 0000000000000001
[ 633.145192] ffff88002804ff68 ffffffff8153d520 0000000000000001 ffff88002804ff38
[ 633.145192] ffffffff810161dc ffff88002804ff58 ffffffff81316ab4 0000000000000046
[ 633.145192] Call Trace:
[ 633.145192] <IRQ> [<ffffffff810161dc>] ? show_stack+0x1c/0x20
[ 633.145192] [<ffffffff81316ab4>] ? showacpu+0x54/0x70
[ 633.145192] [<ffffffff8109437a>] ? generic_smp_call_function_single_interrupt+0x9a/0xd0
[ 633.145192] [<ffffffff8102b0a7>] ? smp_call_function_single_interrupt+0x27/0x40
[ 633.145192] [<ffffffff81012ed3>] ? call_function_single_interrupt+0x13/0x20
[ 633.145192] <EOI> [<ffffffff81516e78>] ? __mutex_unlock_slowpath+0xd8/0x170
[ 633.145192] [<ffffffff81516f1e>] ? mutex_unlock+0xe/0x10
[ 633.145192] [<ffffffff81136859>] ? pipe_write+0x379/0x510
[ 633.145192] [<ffffffff8112d73a>] ? do_sync_write+0xfa/0x140
[ 633.145192] [<ffffffff81078e30>] ? autoremove_wake_function+0x0/0x40
[ 633.145192] [<ffffffff81518c0b>] ? _spin_unlock+0x2b/0x40
[ 633.234178] [<ffffffff81182c0a>] ? pde_users_dec+0x4a/0x60
[ 633.234178] [<ffffffff812343b6>] ? security_file_permission+0x16/0x20
[ 633.234178] [<ffffffff8112da38>] ? vfs_write+0xb8/0x1a0
[ 633.234178] [<ffffffff8112e501>] ? sys_write+0x51/0x90
[ 633.234178] [<ffffffff81012072>] ? system_call_fastpath+0x16/0x1b
[ 722.645266] INFO: task events/0:9 blocked for more than 120 seconds.
[ 722.651662] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 722.659512] events/0 D 0000000000000246 0 9 2 0x00000000
[ 722.666469] ffff88011baebd10 0000000000000046 ffff880028061d98 0000000000000002
[ 722.673977] 0000000000000002 ffff88011bae4790 ffff88011bae4000 ffffffff81518b60
[ 722.681484] 0000000000000000 ffff88011bae4000 ffff88011bae43b0 000000000000fa08
[ 722.688965] Call Trace:
[ 722.691449] [<ffffffff81518b60>] ? _spin_unlock_irq+0x30/0x40
[ 722.697307] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 722.703592] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 722.709747] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 722.717267] [<ffffffffa006fa6f>] ? i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 722.724770] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 722.730555] [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 722.737995] [<ffffffffa006fa30>] ? i915_gem_retire_work_handler+0x0/0x90 [i915]
[ 722.745416] [<ffffffff810734d0>] worker_thread+0x1d0/0x380
[ 722.751007] [<ffffffff8107347e>] ? worker_thread+0x17e/0x380
[ 722.756778] [<ffffffff81048f98>] ? complete+0x28/0x60
[ 722.761939] [<ffffffff81518bb0>] ? _spin_unlock_irqrestore+0x40/0x70
[ 722.768402] [<ffffffff81078e30>] ? autoremove_wake_function+0x0/0x40
[ 722.774863] [<ffffffff81073300>] ? worker_thread+0x0/0x380
[ 722.780458] [<ffffffff81078a4e>] kthread+0x9e/0xb0
[ 722.785357] [<ffffffff8101326a>] child_rip+0xa/0x20
[ 722.790349] [<ffffffff81012bd0>] ? restore_args+0x0/0x30
[ 722.795766] [<ffffffff810789b0>] ? kthread+0x0/0xb0
[ 722.800754] [<ffffffff81013260>] ? child_rip+0x0/0x20
[ 722.805917] 3 locks held by events/0/9:
[ 722.809776] #0: (events){+.+.+.}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 722.817917] #1: (&(&dev_priv->mm.retire_work)->work){+.+...}, at: [<ffffffff8107347e>] worker_thread+0x17e/0x380
[ 722.828374] #2: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fa6f>] i915_gem_retire_work_handler+0x3f/0x90 [i915]
[ 722.839285] INFO: task X:4100 blocked for more than 120 seconds.
[ 722.845313] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 722.853154] X D 0000000000000246 0 4100 4094 0x00400004
[ 722.860088] ffff880116929c48 0000000000000046 ffff880116929bb8 0000000000000246
[ 722.867592] 0000000000000000 ffff8800db2b4740 0000000000000000 ffff880114937928
[ 722.875098] ffff880116929bc8 ffff8800db2b4000 ffff8800db2b43b0 000000000000fa08
[ 722.882602] Call Trace:
[ 722.885082] [<ffffffff815172d5>] ? __mutex_lock_common+0x215/0x3c0
[ 722.891366] [<ffffffff81517231>] __mutex_lock_common+0x171/0x3c0
[ 722.897502] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 722.904591] [<ffffffffa006fd60>] ? i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 722.911655] [<ffffffff81517535>] mutex_lock_nested+0x35/0x40
[ 722.917443] [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 722.924369] [<ffffffffa0034db5>] drm_ioctl+0x185/0x3b0 [drm]
[ 722.930133] [<ffffffff8107c7d1>] ? lock_hrtimer_base+0x31/0x60
[ 722.936076] [<ffffffff8113cd2c>] vfs_ioctl+0x7c/0xa0
[ 722.941205] [<ffffffff8113ce7a>] do_vfs_ioctl+0x8a/0x5a0
[ 722.946632] [<ffffffff81062b71>] ? do_setitimer+0x2f1/0x380
[ 722.952314] [<ffffffff810120aa>] ? sysret_check+0x2e/0x69
[ 722.957820] [<ffffffff8113d411>] sys_ioctl+0x81/0xa0
[ 722.962902] [<ffffffff81012072>] system_call_fastpath+0x16/0x1b
[ 722.968928] 1 lock held by X/4100:
[ 722.972351] #0: (&dev->struct_mutex){+.+.+.}, at: [<ffffffffa006fd60>] i915_gem_throttle_ioctl+0x40/0x90 [i915]
[ 783.789646] SysRq : Emergency Remount R/O
[ 784.243212] Emergency Remount complete
[ 784.280999] tracker-indexer[4443] trap int3 ip:7fb2856fd8b0 sp:7fff41fcace0 error:0
[ 785.715311] SysRq : Emergency Sync
[ 785.719235] Emergency Sync complete
[ 787.647067] SysRq : Resetting
More information about the Intel-gfx
mailing list