login
Search
--Boundary-01=_txO4IuP7tKQ441R
Content-Type: text/plain;
charset="us-ascii"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline
I needed to quickly check some things on multipath; I only had available
notebook with USB stick so I arranged for single path multipath with
faked WWID (which worked by the way).
Testing what happans when last path is lost, I started IO from dm and
removed USB stick. It resulted in quite a long kernel warning about
unsafe lock order. I tested several times and once more got warning
about possible inconsistent lock state.
I attach 2 logs with kernel output (one of them includes dmesg from boot)
and kernel config. Please let me now if additional information is required.
-andrey
--Boundary-01=_txO4IuP7tKQ441R
Content-Type: text/x-log;
charset="us-ascii";
name="multipath-1.log"
Content-Transfer-Encoding: quoted-printable
Content-Disposition: inline;
filename="multipath-1.log"
Sep 29 17:51:06 cooker kernel: [42498.249469] usb 1-1: USB disconnect, addr=
ess 2
Sep 29 17:51:08 cooker sudo: bor : TTY=3Dpts/0 ; PWD=3D/home/bor/src/l=
inux-git/drivers/md ; USER=3Droot ; COMMAND=3D/bin/dd if=3D/dev/mapper/1234=
5678 of=3D/dev/null count=3D10
Sep 29 17:51:08 cooker multipathd: 8:0: mark as failed=20
Sep 29 17:51:08 cooker multipathd: 12345678: Entering recovery mode: max_re=
tries=3D5=20
Sep 29 17:51:08 cooker multipathd: 12345678: remaining active paths: 0=20
Sep 29 17:51:08 cooker kernel: [42500.275610] device-mapper: multipath: Fai=
ling path 8:0.
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] =3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
Sep 29 17:51:08 cooker kernel: [42500.275832] [ INFO: soft-safe -> soft-uns=
afe lock order detected ]
Sep 29 17:51:08 cooker kernel: [42500.275832] 2.6.27-rc7-1avb #11
Sep 29 17:51:08 cooker kernel: [42500.275832] -----------------------------=
=2D------------------------
Sep 29 17:51:08 cooker kernel: [42500.275832] dd/28259 [HC0[0]:SC0[0]:HE0:S=
E1] is trying to acquire:
Sep 29 17:51:08 cooker kernel: [42500.275832] (_minor_lock){--..}, at: [<c=
01f4c68>] _atomic_dec_and_lock+0x18/0x40
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] and this task is already hold=
ing:
Sep 29 17:51:08 cooker kernel: [42500.275832] (&m->lock){....}, at: [<dfec=
4af6>] fail_path+0x26/0xc0 [dm_multipath]
Sep 29 17:51:08 cooker kernel: [42500.275832] which would create a new lock=
dependency:
Sep 29 17:51:08 cooker kernel: [42500.275832] (&m->lock){....} -> (_minor_=
lock){--..}
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] but this new dependency conne=
cts a soft-irq-safe lock:
Sep 29 17:51:08 cooker kernel: [42500.275832] (&q->__queue_lock){.+..}
Sep 29 17:51:08 cooker kernel: [42500.275832] ... which became soft-irq-saf=
e at:
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c013e838>] __lock_acquire=
+0x4d8/0x1640
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c013f9fc>] lock_acquire+0=
x5c/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c02f6aca>] _spin_lock+0x3=
a/0x70
Sep 29 17:51:08 cooker kernel: [42500.275832] [<dfe8a746>] scsi_device_un=
busy+0x76/0xa0 [scsi_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] [<dfe836d1>] scsi_finish_co=
mmand+0x21/0xe0 [scsi_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] [<dfe8a943>] scsi_softirq_d=
one+0x103/0x120 [scsi_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c01e81fd>] blk_done_softi=
rq+0x6d/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c0120612>] __do_softirq+0=
x62/0xc0
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c0104c56>] do_softirq+0x9=
6/0xd0
Sep 29 17:51:08 cooker kernel: [42500.275832] [<ffffffff>] 0xffffffff
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] to a soft-irq-unsafe lock:
Sep 29 17:51:08 cooker kernel: [42500.275832] (_minor_lock){--..}
Sep 29 17:51:08 cooker kernel: [42500.275832] ... which became soft-irq-uns=
afe at:
Sep 29 17:51:08 cooker kernel: [42500.275832] ... [<c013e87d>] __lock_acqu=
ire+0x51d/0x1640
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c013f9fc>] lock_acquire+0=
x5c/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c02f6aca>] _spin_lock+0x3=
a/0x70
Sep 29 17:51:08 cooker kernel: [42500.275832] [<dfda0f39>] dm_create+0x3d=
9/0x480 [dm_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] [<dfda56c1>] dev_create+0x4=
1/0x280 [dm_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] [<dfda5b5b>] dm_ctl_ioctl+0=
x1cb/0x240 [dm_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c01998c5>] vfs_ioctl+0x25=
/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c0199b5a>] do_vfs_ioctl+0=
x23a/0x2b0
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c0199c0e>] sys_ioctl+0x3e=
/0x70
Sep 29 17:51:08 cooker kernel: [42500.275832] [<c010329d>] sysenter_do_ca=
ll+0x12/0x31
Sep 29 17:51:08 cooker kernel: [42500.275832] [<ffffffff>] 0xffffffff
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] other info that might help us=
debug this:
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] 2 locks held by dd/28259:
Sep 29 17:51:08 cooker kernel: [42500.275832] #0: (&q->__queue_lock){.+..=
}, at: [<c01e9c4f>] generic_unplug_device+0x1f/0x40
Sep 29 17:51:08 cooker kernel: [42500.275832] #1: (&m->lock){....}, at: [=
<dfec4af6>] fail_path+0x26/0xc0 [dm_multipath]
Sep 29 17:51:08 cooker kernel: [42500.275832]=20
Sep 29 17:51:08 cooker kernel: [42500.275832] the soft-irq-safe lock's depe=
ndencies:
Sep 29 17:51:08 cooker kernel: [42500.275832] -> (&q->__queue_lock){.+..} o=
ps: 0 {
Sep 29 17:51:08 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c01eb873>] blk_queue_stack_limits+0xc3/0x160
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<dfd93211>] linear_conf+0xb1/0x300 [linear]
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<dfd9356a>] linear_run+0x2a/0x90 [linear]
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<dfe75e49>] do_md_run+0x439/0xa90 [md_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<dfe7805a>] md_ioctl+0xdba/0x1780 [md_mod]
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c01ed2fb>] blkdev_driver_ioctl+0x5b/0x60
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c01ed59d>] blkdev_ioctl+0x29d/0x860
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c01b6770>] block_ioctl+0x20/0x30
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c01998c5>] vfs_ioctl+0x25/0x80
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c0199b5a>] do_vfs_ioctl+0x23a/0x2b0
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c0199c0e>] sys_ioctl+0x3e/0x70
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<c010329d>] sysenter_do_call+0x12/0x31
Sep 29 17:51:08 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:08 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a746>] scsi_device_unbusy+0x76/0xa0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe836d1>] scsi_finish_command+0x21/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a943>] scsi_softirq_done+0x103/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e81fd>] blk_done_softirq+0x6d/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c0927ee8>=
] __key.22527+0x0/0x8
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&ret->lock){++..} ops: 0=
{
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f3962>] cfq_set_request+0x232/0x2f0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e6af4>] elv_set_request+0x24/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e95d4>] get_request+0x264/0x290
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e9ac2>] get_request_wait+0x22/0x110
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e9c0b>] blk_get_request+0x5b/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df837ecf>] ide_raw_taskfile+0x2f/0x90 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df838062>] taskfile_lib_get_identify+0x62/0x70 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df83cc01>] ide_acpi_port_init_devices+0xd1/0xec [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df83785c>] ide_host_register+0x5bc/0x6c0 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df839e6f>] ide_pci_init_one+0xdf/0x100 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df81860e>] alim15x3_init_one+0xae/0xf5 [alim15x3]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02102ee>] pci_device_probe+0x5e/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c026431c>] driver_probe_device+0x8c/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02644c1>] __driver_attach+0x81/0x90
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0263b8b>] bus_for_each_dev+0x5b/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c026417e>] driver_attach+0x1e/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02634ff>] bus_add_driver+0x1cf/0x250
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02646a1>] driver_register+0x71/0x150
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0210533>] __pci_register_driver+0x63/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df81201c>] 0xdf81201c
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c010111b>] do_one_initcall+0x2b/0x170
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01493da>] sys_init_module+0x8a/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0103376>] syscall_call+0x7/0xb
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f26d3>] cic_free_func+0x23/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f2750>] cfq_free_io_context+0x30/0x40
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01ebc4b>] put_io_context+0x8b/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f2b12>] __cfq_slice_expired+0x52/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f2c7c>] cfq_put_queue+0x7c/0xa0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f2e29>] cfq_put_request+0x49/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e6b38>] elv_put_request+0x18/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e8e31>] __blk_put_request+0x81/0xa0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e8ecc>] end_that_request_last+0x7c/0x200
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e91d0>] __blk_end_request+0x30/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df831977>] __ide_end_request+0x37/0xd0 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df831ac9>] ide_end_request+0x59/0x90 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df838315>] task_end_request+0x35/0x80 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df83ad16>] ide_dma_intr+0x56/0xa0 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df832b48>] ide_intr+0x98/0x220 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f26d3>] cic_free_func+0x23/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f2750>] cfq_free_io_context+0x30/0x40
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01ebc4b>] put_io_context+0x8b/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f2e14>] cfq_put_request+0x34/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e6b38>] elv_put_request+0x18/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e8e31>] __blk_put_request+0x81/0xa0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e8ecc>] end_that_request_last+0x7c/0x200
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e90ae>] blk_end_io+0x5e/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e918f>] blk_end_request+0x1f/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe89d2d>] scsi_end_request+0x2d/0x90 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a32c>] scsi_io_completion+0x13c/0x490 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe83733>] scsi_finish_command+0x83/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a943>] scsi_softirq_done+0x103/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e81fd>] blk_done_softirq+0x6d/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c0927f1c=
Sep 29 17:51:09 cooker kernel: [42500.275832] ... acquired at:
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f3b1>] __lock_acquir=
e+0x1051/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f9fc>] lock_acquire+=
0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c02f6c29>] _spin_lock_ir=
qsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01f31b3>] cfq_cic_looku=
p+0x93/0x160
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01f354e>] cfq_get_queue=
+0x6e/0x1d0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01f3934>] cfq_set_reque=
st+0x204/0x2f0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e6af4>] elv_set_reque=
st+0x24/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e95d4>] get_request+0=
x264/0x290
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e9ac2>] get_request_w=
ait+0x22/0x110
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e9c0b>] blk_get_reque=
st+0x5b/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8adc8>] scsi_execute+=
0x28/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8af2f>] scsi_execute_=
req+0x6f/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8c0a5>] scsi_probe_an=
d_add_lun+0x245/0xc50 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8cd54>] __scsi_scan_t=
arget+0xf4/0x710 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d3cf>] scsi_scan_cha=
nnel+0x5f/0xa0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d4c9>] scsi_scan_hos=
t_selected+0xb9/0x180 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d60c>] do_scsi_scan_=
host+0x7c/0x80 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d8ce>] scsi_scan_hos=
t+0x14e/0x1b0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfea3a9d>] usb_stor_scan=
_thread+0x14d/0x190 [usb_storage]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c012f58f>] kthread+0x3f/=
0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01035df>] kernel_thread=
_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] [<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832]=20
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&base->lock){++..} ops: =
0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012498a>] lock_timer_base+0x2a/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0124ae6>] __mod_timer+0x36/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0124c4f>] mod_timer+0x3f/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03f5c6e>] con_init+0x22d/0x232
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03f5192>] console_init+0x1e/0x2c
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de958>] start_kernel+0x196/0x2f0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de33d>] i386_start_kernel+0x9d/0xa9
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012498a>] lock_timer_base+0x2a/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01249d8>] del_timer+0x28/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<df832b25>] ide_intr+0x75/0x220 [ide_core]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6bb0>] _spin_lock_irq+0x40/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012411e>] run_timer_softirq+0x2e/0x1e0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c044156c=
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (ftrace_shutdown_lock){+=
+..} ops: 0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0160f5d>] ftrace_record_ip+0x9d/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035f7>] mcount_call+0x5/0xa
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de51d>] kernel_init+0x7a/0xcc
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035df>] kernel_thread_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0160f5d>] ftrace_record_ip+0x9d/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035f7>] mcount_call+0x5/0xa
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0160f5d>] ftrace_record_ip+0x9d/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035f7>] mcount_call+0x5/0xa
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c03c26b=
0>] ftrace_shutdown_lock+0x10/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] ... acquired at:
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f3b1>] __lock_acquir=
e+0x1051/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f9fc>] lock_acquire+=
0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c02f6c29>] _spin_lock_ir=
qsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c0160f5d>] ftrace_record=
_ip+0x9d/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01035f7>] mcount_call+0=
x5/0xa
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c0124b48>] __mod_timer+0=
x98/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c02f45a4>] schedule_time=
out+0x74/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c016135e>] ftraced+0x5e/=
0x180
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c012f58f>] kthread+0x3f/=
0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01035df>] kernel_thread=
_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] [<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832]=20
Sep 29 17:51:09 cooker kernel: [42500.275832] ... acquired at:
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f3b1>] __lock_acquir=
e+0x1051/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f9fc>] lock_acquire+=
0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c02f6c29>] _spin_lock_ir=
qsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c012498a>] lock_timer_ba=
se+0x2a/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c0124ae6>] __mod_timer+0=
x36/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c0124c4f>] mod_timer+0x3=
f/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e9cb7>] blk_plug_devi=
ce+0x47/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e7b9e>] __elv_add_req=
uest+0x5e/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01ec2f7>] blk_execute_r=
q_nowait+0x57/0xa0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01ec3ee>] blk_execute_r=
q+0xae/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8ae79>] scsi_execute+=
0xd9/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8af2f>] scsi_execute_=
req+0x6f/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8c0a5>] scsi_probe_an=
d_add_lun+0x245/0xc50 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8cd54>] __scsi_scan_t=
arget+0xf4/0x710 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d3cf>] scsi_scan_cha=
nnel+0x5f/0xa0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d4c9>] scsi_scan_hos=
t_selected+0xb9/0x180 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d60c>] do_scsi_scan_=
host+0x7c/0x80 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d8ce>] scsi_scan_hos=
t+0x14e/0x1b0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfea3a9d>] usb_stor_scan=
_thread+0x14d/0x190 [usb_storage]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c012f58f>] kthread+0x3f/=
0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01035df>] kernel_thread=
_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] [<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832]=20
Sep 29 17:51:09 cooker kernel: [42500.275832] ... acquired at:
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f3b1>] __lock_acquir=
e+0x1051/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c013f9fc>] lock_acquire+=
0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c02f6c29>] _spin_lock_ir=
qsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c0160f5d>] ftrace_record=
_ip+0x9d/0x1b0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01035f7>] mcount_call+0=
x5/0xa
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01e99d3>] __generic_unp=
lug_device+0x23/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01ec2fe>] blk_execute_r=
q_nowait+0x5e/0xa0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01ec3ee>] blk_execute_r=
q+0xae/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8ae79>] scsi_execute+=
0xd9/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8af2f>] scsi_execute_=
req+0x6f/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8c0a5>] scsi_probe_an=
d_add_lun+0x245/0xc50 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8cd54>] __scsi_scan_t=
arget+0xf4/0x710 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d3cf>] scsi_scan_cha=
nnel+0x5f/0xa0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d4c9>] scsi_scan_hos=
t_selected+0xb9/0x180 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d60c>] do_scsi_scan_=
host+0x7c/0x80 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfe8d8ce>] scsi_scan_hos=
t+0x14e/0x1b0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<dfea3a9d>] usb_stor_scan=
_thread+0x14d/0x190 [usb_storage]
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c012f58f>] kthread+0x3f/=
0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] [<c01035df>] kernel_thread=
_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] [<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832]=20
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&sdev->list_lock){.+..} =
ops: 0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe843f9>] scsi_get_command+0x59/0xa0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a15c>] scsi_setup_blk_pc_cmnd+0x10c/0x150 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a1e5>] scsi_prep_fn+0x45/0x50 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e786d>] elv_next_request+0xdd/0x1e0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe895c4>] scsi_request_fn+0x64/0x430 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e99d3>] __generic_unplug_device+0x23/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01ec2fe>] blk_execute_rq_nowait+0x5e/0xa0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01ec3ee>] blk_execute_rq+0xae/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8ae79>] scsi_execute+0xd9/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8af2f>] scsi_execute_req+0x6f/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8c0a5>] scsi_probe_and_add_lun+0x245/0xc50 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8cd54>] __scsi_scan_target+0xf4/0x710 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8d3cf>] scsi_scan_channel+0x5f/0xa0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8d4c9>] scsi_scan_host_selected+0xb9/0x180 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8d60c>] do_scsi_scan_host+0x7c/0x80 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8d8ce>] scsi_scan_host+0x14e/0x1b0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfea3a9d>] usb_stor_scan_thread+0x14d/0x190 [usb_storage]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012f58f>] kthread+0x3f/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035df>] kernel_thread_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe84357>] scsi_put_command+0x17/0x60 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe89b81>] scsi_next_command+0x31/0x50 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe89d80>] scsi_end_request+0x80/0x90 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a32c>] scsi_io_completion+0x13c/0x490 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe83733>] scsi_finish_command+0x83/0xe0 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<dfe8a943>] scsi_softirq_done+0x103/0x120 [scsi_mod]
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01e81fd>] blk_done_softirq+0x6d/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<dfe9eec8=
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&cwq->lock){++..} ops: =
0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c44f>] __queue_work+0x1f/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c4d1>] queue_work_on+0x31/0x40
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c593>] queue_work+0x13/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012b6ca>] call_usermodehelper_exec+0xca/0xf0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f6d57>] kobject_uevent_env+0x357/0x390
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f6d9a>] kobject_uevent+0xa/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01f63d9>] kset_register+0x49/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0263e0d>] bus_register+0xad/0x230
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03f5dd6>] platform_bus_init+0x28/0x3a
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03f5ea5>] driver_init+0x25/0x39
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de4fd>] kernel_init+0x5a/0xcc
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035df>] kernel_thread_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c44f>] __queue_work+0x1f/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c4d1>] queue_work_on+0x31/0x40
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c593>] queue_work+0x13/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0226613>] acpi_os_execute+0x81/0x9d
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c022ce16>] acpi_ev_gpe_dispatch+0xa2/0x10f
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c022cf22>] acpi_ev_gpe_detect+0x9f/0xe6
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c022b491>] acpi_ev_sci_xrupt_handler+0x1a/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0225e8f>] acpi_irq+0x13/0x28
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c44f>] __queue_work+0x1f/0x50
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c4d1>] queue_work_on+0x31/0x40
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c593>] queue_work+0x13/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012c5b4>] schedule_work+0x14/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c021cad9>] cursor_timer_handler+0x19/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012421a>] run_timer_softirq+0x12a/0x1e0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c04415c=
c>] __key.18975+0x0/0x8
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&q->lock){++..} ops: 0=
{
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6bb0>] _spin_lock_irq+0x40/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f433e>] wait_for_common+0x2e/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f44d2>] wait_for_completion+0x12/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012f71e>] kthread_create+0x6e/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03ff030>] cpu_callback+0x50/0x90
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03eb91b>] spawn_ksoftirqd+0x19/0x39
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c010111b>] do_one_initcall+0x2b/0x170
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de4dc>] kernel_init+0x39/0xcc
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035df>] kernel_thread_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c011309b>] complete+0x1b/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f25f4>] i8042_aux_test_irq+0x67/0x69
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c011309b>] complete+0x1b/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012d490>] wakeme_after_rcu+0x10/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015c4e2>] __rcu_process_callbacks+0x82/0x200
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015c677>] rcu_process_callbacks+0x17/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c04419=
94>] __key.13745+0x0/0x8
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&rq->lock){++..} ops:=
0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6c29>] _spin_lock_irqsave+0x49/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03fef2f>] init_idle+0x73/0xbf
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03eb45b>] sched_init+0x343/0x392
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de885>] start_kernel+0xc3/0x2f0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c03de33d>] i386_start_kernel+0x9d/0xa9
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01176a7>] scheduler_tick+0x27/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0124701>] update_process_times+0x41/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0139119>] tick_periodic+0x29/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0139187>] tick_handle_periodic+0x17/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104cb9>] timer_interrupt+0x19/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0113f27>] task_rq_lock+0x17/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c011462f>] try_to_wake_up+0x2f/0x140
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c011474b>] default_wake_function+0xb/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01124f8>] __wake_up_common+0x48/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01130bd>] complete+0x3d/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012d490>] wakeme_after_rcu+0x10/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015c4e2>] __rcu_process_callbacks+0x82/0x200
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015c677>] rcu_process_callbacks+0x17/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c041f=
22c>] __key.35092+0x0/0x8
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&rt_b->rt_runtime_lo=
ck){++..} ops: 0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0114f32>] enqueue_task_rt+0xb2/0x120
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01120e0>] activate_task+0x50/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0117089>] __sched_setscheduler+0x199/0x360
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0117282>] sched_setscheduler+0x12/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015993a>] watchdog+0x2a/0x200
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012f58f>] kthread+0x3f/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01035df>] kernel_thread_helper+0x7/0x10
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0114f32>] enqueue_task_rt+0xb2/0x120
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01120e0>] activate_task+0x50/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01146ef>] try_to_wake_up+0xef/0x140
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c011476f>] wake_up_process+0xf/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0159c62>] softlockup_tick+0x152/0x170
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012465c>] run_local_timers+0x1c/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01246e8>] update_process_times+0x28/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01398fe>] tick_sched_timer+0x4e/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013300c>] __run_hrtimer+0x9c/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0133a7f>] hrtimer_interrupt+0x14f/0x1c0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104cb9>] timer_interrupt+0x19/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0114f32>] enqueue_task_rt+0xb2/0x120
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01120e0>] activate_task+0x50/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01146ef>] try_to_wake_up+0xef/0x140
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c011476f>] wake_up_process+0xf/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0132f5d>] hrtimer_wakeup+0x1d/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013300c>] __run_hrtimer+0x9c/0xb0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0133a7f>] hrtimer_interrupt+0x14f/0x1c0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104cb9>] timer_interrupt+0x19/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] }
Sep 29 17:51:09 cooker kernel: [42500.275832] ... key at: [<c041=
f234>] __key.30911+0x0/0x8
Sep 29 17:51:09 cooker kernel: [42500.275832] -> (&cpu_base->lock){++=
=2E.} ops: 0 {
Sep 29 17:51:09 cooker kernel: [42500.275832] initial-use at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e4c0>] __lock_acquire+0x160/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6bb0>] _spin_lock_irq+0x40/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0132d46>] run_hrtimer_pending+0x16/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01332af>] hrtimer_run_pending+0x3f/0x110
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0124109>] run_timer_softirq+0x19/0x1e0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0120612>] __do_softirq+0x62/0xc0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104c56>] do_softirq+0x96/0xd0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-hardirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013ea2b>] __lock_acquire+0x6cb/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6aca>] _spin_lock+0x3a/0x70
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0133079>] hrtimer_run_queues+0x59/0x1a0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c012464d>] run_local_timers+0xd/0x30
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c01246e8>] update_process_times+0x28/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0139119>] tick_periodic+0x29/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0139187>] tick_handle_periodic+0x17/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104cb9>] timer_interrupt+0x19/0x20
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015a271>] handle_IRQ_event+0x31/0x60
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c015b6fe>] handle_level_irq+0x6e/0xe0
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c0104b40>] do_IRQ+0xb0/0x130
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<ffffffff>] 0xffffffff
Sep 29 17:51:09 cooker kernel: [42500.275832] in-softirq-W at:
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013e838>] __lock_acquire+0x4d8/0x1640
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c013f9fc>] lock_acquire+0x5c/0x80
Sep 29 17:51:09 cooker kernel: [42500.275832] =
[<c02f6
