読者です 読者をやめる 読者になる 読者になる

oops

久々にoopsが出たので貼り付け。
多分下のコマンド実行中でのoops発生と思う。
sudo dd if=/dev/zero of=/dev/sdf bs=1M

[287041.483833] INFO: task hald-addon-stor:19153 blocked for more than 120 seconds.
[287041.483841] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[287041.483846] hald-addon-st D ffff880056608e28     0 19153   3549 0x00000000
[287041.483860] __ratelimit: 374 callbacks suppressed
[287041.483865] BUG: using smp_processor_id() in preemptible [00000000] code: khungtaskd/262
[287041.483875] caller is show_stack_log_lvl+0x3c/0x133
[287041.483881] Pid: 262, comm: khungtaskd Not tainted 2.6.31-rc9-x86tip-tip #1
[287041.483886] Call Trace:
[287041.483899]  [<ffffffff8123ed44>] debug_smp_processor_id+0xe0/0x114
[287041.483906]  [<ffffffff81016b1e>] show_stack_log_lvl+0x3c/0x133
[287041.483913]  [<ffffffff810181d9>] show_stack+0x2f/0x45
[287041.483923]  [<ffffffff8105ccbd>] sched_show_task+0xc6/0xe1
[287041.483931]  [<ffffffff810deecb>] watchdog+0x213/0x295
[287041.483937]  [<ffffffff810ded32>] ? watchdog+0x7a/0x295
[287041.483943]  [<ffffffff810decb8>] ? watchdog+0x0/0x295
[287041.483951]  [<ffffffff81095705>] kthread+0x9d/0xa5
[287041.483958]  [<ffffffff8101439a>] child_rip+0xa/0x20
[287041.483967]  [<ffffffff81013cd4>] ? restore_args+0x0/0x30
[287041.483974]  [<ffffffff81095668>] ? kthread+0x0/0xa5
[287041.483980]  [<ffffffff81014390>] ? child_rip+0x0/0x20
[287041.483984]  ffff88005991db88 0000000000000086 ffff88005991dad8 ffffffff810a97ad
[287041.484065]  00000000810d092a 0000000000000000 ffff88005991c000 0000000000011b40
[287041.484096]  ffff88005991dfd8 ffff88004dc6bcf0 ffff8800b48b6590 ffff88004dc6c008
[287041.484120] Call Trace:
[287041.484133]  [<ffffffff810a97ad>] ? put_lock_stats+0x3b/0x5a
[287041.484147]  [<ffffffff813d36a4>] ? __mutex_lock_common+0x2ac/0x4ce
[287041.484157]  [<ffffffff810ab703>] ? trace_hardirqs_on_caller+0x123/0x15e
[287041.484167]  [<ffffffff813d36d7>] __mutex_lock_common+0x2df/0x4ce
[287041.484179]  [<ffffffff81192138>] ? __blkdev_get+0x91/0x385
[287041.484190]  [<ffffffff81192138>] ? __blkdev_get+0x91/0x385
[287041.484201]  [<ffffffff813d39db>] mutex_lock_nested+0x4a/0x67
[287041.484210]  [<ffffffff81192138>] __blkdev_get+0x91/0x385
[287041.484221]  [<ffffffff8119244f>] blkdev_get+0x23/0x39
[287041.484231]  [<ffffffff811924ea>] blkdev_open+0x85/0xd1
[287041.484244]  [<ffffffff811559f6>] __dentry_open+0x224/0x368
[287041.484254]  [<ffffffff81192465>] ? blkdev_open+0x0/0xd1
[287041.484266]  [<ffffffff81167846>] ? inode_permission+0xb2/0xd5
[287041.484276]  [<ffffffff81155c48>] nameidata_to_filp+0x55/0x7d
[287041.484288]  [<ffffffff8116a0b5>] do_filp_open+0x536/0xa40
[287041.484298]  [<ffffffff810a97ad>] ? put_lock_stats+0x3b/0x5a
[287041.484310]  [<ffffffff810a9891>] ? lock_release_holdtime+0xc5/0xe1
[287041.484321]  [<ffffffff813d5058>] ? _spin_unlock+0x5e/0x82
[287041.484332]  [<ffffffff8117817e>] ? alloc_fd+0x131/0x156
[287041.484345]  [<ffffffff81155653>] do_sys_open+0x70/0x12e
[287041.484357]  [<ffffffff8115577e>] sys_open+0x33/0x49
[287041.484368]  [<ffffffff81013172>] system_call_fastpath+0x16/0x1b
[287041.484377] 1 lock held by hald-addon-stor/19153:
[287041.484384]  #0:  (&bdev->bd_mutex){+.+.+.}, at: [<ffffffff81192138>] __blkdev_get+0x91/0x385
[287041.484412] INFO: task hald-probe-stor:19231 blocked for more than 120 seconds.
[287041.484418] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[287041.484425] hald-probe-st D ffff880056608e28     0 19231   3549 0x00000004
[287041.484443] BUG: using smp_processor_id() in preemptible [00000000] code: khungtaskd/262
[287041.484451] caller is show_stack_log_lvl+0x3c/0x133
[287041.484457] Pid: 262, comm: khungtaskd Not tainted 2.6.31-rc9-x86tip-tip #1
[287041.484464] Call Trace:
[287041.484474]  [<ffffffff8123ed44>] debug_smp_processor_id+0xe0/0x114
[287041.484485]  [<ffffffff81016b1e>] show_stack_log_lvl+0x3c/0x133
[287041.484496]  [<ffffffff810181d9>] show_stack+0x2f/0x45
[287041.484507]  [<ffffffff8105ccbd>] sched_show_task+0xc6/0xe1
[287041.484517]  [<ffffffff810deecb>] watchdog+0x213/0x295
[287041.484527]  [<ffffffff810ded32>] ? watchdog+0x7a/0x295
[287041.484537]  [<ffffffff810decb8>] ? watchdog+0x0/0x295
[287041.484547]  [<ffffffff81095705>] kthread+0x9d/0xa5
[287041.484560]  [<ffffffff8101439a>] child_rip+0xa/0x20
[287041.484571]  [<ffffffff81013cd4>] ? restore_args+0x0/0x30
[287041.484580]  [<ffffffff81095668>] ? kthread+0x0/0xa5
[287041.484592]  [<ffffffff81014390>] ? child_rip+0x0/0x20
[287041.484598]  ffff8800aed15b88 0000000000000086 ffff8800aed15ad8 ffffffff810a97ad
[287041.484623]  0000000030c9018c 0000000000000000 ffff8800aed14000 0000000000011b40
[287041.484648]  ffff8800aed15fd8 ffff8800b2155140 ffff8800b2150000 ffff8800b2155458
[287041.484673] Call Trace:
[287041.484683]  [<ffffffff810a97ad>] ? put_lock_stats+0x3b/0x5a
[287041.484694]  [<ffffffff813d36a4>] ? __mutex_lock_common+0x2ac/0x4ce
[287041.484703]  [<ffffffff810ab703>] ? trace_hardirqs_on_caller+0x123/0x15e
[287041.484715]  [<ffffffff813d36d7>] __mutex_lock_common+0x2df/0x4ce
[287041.484724]  [<ffffffff81192138>] ? __blkdev_get+0x91/0x385
[287041.484735]  [<ffffffff81192138>] ? __blkdev_get+0x91/0x385
[287041.484746]  [<ffffffff813d39db>] mutex_lock_nested+0x4a/0x67
[287041.484757]  [<ffffffff81192138>] __blkdev_get+0x91/0x385
[287041.484769]  [<ffffffff8119244f>] blkdev_get+0x23/0x39
[287041.484780]  [<ffffffff811924ea>] blkdev_open+0x85/0xd1
[287041.484791]  [<ffffffff811559f6>] __dentry_open+0x224/0x368
[287041.484801]  [<ffffffff81192465>] ? blkdev_open+0x0/0xd1
[287041.484812]  [<ffffffff81167846>] ? inode_permission+0xb2/0xd5
[287041.484824]  [<ffffffff81155c48>] nameidata_to_filp+0x55/0x7d
[287041.484834]  [<ffffffff8116a0b5>] do_filp_open+0x536/0xa40
[287041.484844]  [<ffffffff810a97ad>] ? put_lock_stats+0x3b/0x5a
[287041.484855]  [<ffffffff810a9891>] ? lock_release_holdtime+0xc5/0xe1
[287041.484868]  [<ffffffff813d5058>] ? _spin_unlock+0x5e/0x82
[287041.484877]  [<ffffffff8117817e>] ? alloc_fd+0x131/0x156
[287041.484888]  [<ffffffff81155653>] do_sys_open+0x70/0x12e
[287041.484900]  [<ffffffff8115577e>] sys_open+0x33/0x49
[287041.484910]  [<ffffffff81013172>] system_call_fastpath+0x16/0x1b
[287041.484918] 1 lock held by hald-probe-stor/19231:
[287041.484924]  #0:  (&bdev->bd_mutex){+.+.+.}, at: [<ffffffff81192138>] __blkdev_get+0x91/0x385

2009/09/10追記
プロセスのハングアップを検知してメッセージを出してるのはhung_task.c、
http://lxr.linux.no/#linux+v2.6.31/kernel/hung_task.c
この中のcheck_hung_task()で以下のメッセージを出す(その他のも)。

[287041.483833] INFO: task hald-addon-stor:19153 blocked for more than 120 seconds.
[287041.483841] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.

ちょうどこの部分。

  92        /*
  93         * Ok, the task did not get scheduled for more than 2 minutes,
  94         * complain:
  95         */
  96        printk(KERN_ERR "INFO: task %s:%d blocked for more than "
  97                        "%ld seconds.\n", t->comm, t->pid, timeout);
  98        printk(KERN_ERR "\"echo 0 > /proc/sys/kernel/hung_task_timeout_secs\""
  99                        " disables this message.\n");
 100        sched_show_task(t);
 101        __debug_show_held_locks(t);
 102