i915 blocked for more than 120 seconds.

[Toralf Förster]

Hello,

today I run at the latest 2.6.35.6 kernel few apps like Lotus Notes under wine, amarok and firefox
and in parallel a "git bisect" when I got this :

n22 ~ # grep 2010-09-29T11:21 /var/log/messages | grep kernel
2010-09-29T11:21:36.984+02:00 n22 kernel: INFO: task i915:868 blocked for more than 120 seconds.
2010-09-29T11:21:36.984+02:00 n22 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
2010-09-29T11:21:36.984+02:00 n22 kernel: i915          D ffa47280     0   868      2 0x00000000
2010-09-29T11:21:36.984+02:00 n22 kernel: f5d0ff1c 00000046 000112be ffa47280 f53f0064 0ec0c353 00000532 f723b804
2010-09-29T11:21:36.984+02:00 n22 kernel: c2406b40 0000118d 00000000 c13b7b00 0ec0b1c6 00000532 c13b1000 c13b1000
2010-09-29T11:21:36.984+02:00 n22 kernel: c13b7b00 c13b7b00 f6384030 f6384030 f5d0ff6c c123c6f1 1ddceea9 0000164f
2010-09-29T11:21:36.984+02:00 n22 kernel: Call Trace:
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c123c6f1>] ? schedule+0x211/0x780
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c123d5c0>] __mutex_lock_slowpath+0xd0/0x130
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c123d980>] mutex_lock+0x20/0x40
2010-09-29T11:21:36.984+02:00 n22 kernel: [<f8a88508>] i915_gem_retire_work_handler+0x28/0xc0 [i915]
2010-09-29T11:21:36.984+02:00 n22 kernel: [<f8a884e0>] ? i915_gem_retire_work_handler+0x0/0xc0 [i915]
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c10441af>] worker_thread+0xef/0x1b0
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c102e6ab>] ? default_wake_function+0xb/0x10
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c1047bd0>] ? autoremove_wake_function+0x0/0x40
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c10440c0>] ? worker_thread+0x0/0x1b0
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c1047864>] kthread+0x74/0x80
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c10477f0>] ? kthread+0x0/0x80
2010-09-29T11:21:36.984+02:00 n22 kernel: [<c10032be>] kernel_thread_helper+0x6/0x18
2010-09-29T11:21:36.984+02:00 n22 kernel: INFO: task X:12082 blocked for more than 120 seconds.
2010-09-29T11:21:36.985+02:00 n22 kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
2010-09-29T11:21:36.985+02:00 n22 kernel: X             D c11cf5a4     0 12082  12029 0x00400004
2010-09-29T11:21:36.985+02:00 n22 kernel: f5fc7dd0 00203082 f5fc7db0 c11cf5a4 ffffffff f2e0157c 00000531 00000000
2010-09-29T11:21:36.985+02:00 n22 kernel: 000004d0 000382ff 00000000 c13b7b00 f2dc927d 00000531 c13b1000 c13b1000
2010-09-29T11:21:36.985+02:00 n22 kernel: c13b7b00 c13b7b00 f516dba0 f5fc7dc4 c11d26e8 00000020 c3680c80 00000020
2010-09-29T11:21:36.985+02:00 n22 kernel: Call Trace:
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c11cf5a4>] ? sock_alloc_send_pskb+0x164/0x2a0
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c11d26e8>] ? skb_queue_tail+0x38/0x50
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c123d5c0>] __mutex_lock_slowpath+0xd0/0x130
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c123d980>] mutex_lock+0x20/0x40
2010-09-29T11:21:36.985+02:00 n22 kernel: [<f8a8843b>] i915_gem_throttle_ioctl+0x2b/0x80 [i915]
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c11cb3f3>] ? sock_aio_write+0x113/0x140
2010-09-29T11:21:36.985+02:00 n22 kernel: [<f89ea08b>] drm_ioctl+0x1ab/0x430 [drm]
2010-09-29T11:21:36.985+02:00 n22 kernel: [<f8a88410>] ? i915_gem_throttle_ioctl+0x0/0x80 [i915]
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c10a2045>] ? do_sync_readv_writev+0x95/0xd0
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c10a1eb2>] ? rw_copy_check_uvector+0x52/0x100
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c10af03d>] vfs_ioctl+0x2d/0xc0
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c10a27c1>] ? do_readv_writev+0x141/0x1b0
2010-09-29T11:21:36.985+02:00 n22 kernel: [<f89e9ee0>] ? drm_ioctl+0x0/0x430 [drm]
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c10af21a>] do_vfs_ioctl+0x6a/0x590
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c104bac3>] ? hrtimer_try_to_cancel+0x33/0x80
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c1036efd>] ? do_setitimer+0xad/0x1a0
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c112c6e8>] ? _copy_from_user+0x38/0x130
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c103703c>] ? sys_setitimer+0x4c/0xa0
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c10af779>] sys_ioctl+0x39/0x60
2010-09-29T11:21:36.985+02:00 n22 kernel: [<c1002d97>] sysenter_do_call+0x12/0x26

The ThinkPad T400 hang completey and I had to use the magic SysRq+B key to reboot the system.
Is this a known problem ?

-- 
MfG/Kind regards
Toralf Förster

pgp finger print: 7B1A 07F4 EC82 0F90 D4C2 8936 872A E508 7DB6 9DA3