3.2.0-rc3-next-20111128 lockdep splat during boot..

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Seen this twice out of two boots while initrd was waiting for me to
enter the passphrase for encrypted LVM.  No idea *who* to toss this
one at - got ACPI, PM, SCSI and vtconsole all listed in tracebacks, so
I'm tossing this at everybody and hope it sticks. ;)

[   14.708463] 
[   14.708747] =========================================================
[   14.709229] [ INFO: possible irq lock inversion dependency detected ]
[   14.709229] 3.2.0-rc3-next-20111128 #41
[   14.709229] ---------------------------------------------------------
[   14.709229] ksoftirqd/0/3 just changed the state of lock:
[   14.709229]  (&(&dev->power.lock)->rlock){..-...}, at: [<ffffffff812f0cea>] pm_suspend_timer_fn+0x1d/0x72
[   14.709229] but this lock took another, SOFTIRQ-unsafe lock in the past:
[   14.709229]  (&(&k->k_lock)->rlock){+.+...}
[   14.709229] 
[   14.709229] and interrupts could create inverse lock ordering between them.
[   14.709229] 
[   14.709229] 
[   14.709229] other info that might help us debug this:
[   14.709229]  Possible interrupt unsafe locking scenario:
[   14.709229] 
[   14.709229]        CPU0                    CPU1
[   14.709229]        ----                    ----
[   14.709229]   lock(&(&k->k_lock)->rlock);
[   14.709229]                                local_irq_disable();
[   14.709229]                                lock(&(&dev->power.lock)->rlock);
[   14.709229]                                lock(&(&k->k_lock)->rlock);
[   14.709229]   <Interrupt>
[   14.709229]     lock(&(&dev->power.lock)->rlock);
[   14.709229] 
[   14.709229]  *** DEADLOCK ***
[   14.709229] 
[   14.709229] 1 lock held by ksoftirqd/0/3:
[   14.709229]  #0:  (&dev->power.suspend_timer){+.-...}, at: [<ffffffff81048e30>] call_timer_fn+0x0/0x1f7
[   14.709229] 
[   14.709229] the shortest dependencies between 2nd lock and 1st lock:
[   14.709229]  -> (&(&k->k_lock)->rlock){+.+...} ops: 7510 {
[   14.709229]     HARDIRQ-ON-W at:
[   14.709229]                                          [<ffffffff8106c4c9>] mark_irqflags+0xd2/0x13e
[   14.709229]                                          [<ffffffff8106c760>] __lock_acquire+0x22b/0x3e2
[   14.709229]                                          [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   14.709229]                                          [<ffffffff815b9b41>] _raw_spin_lock+0x36/0x45
[   14.709229]                                          [<ffffffff81587e52>] klist_add_tail+0x20/0x3f
[   14.709229]                                          [<ffffffff812ea646>] device_add+0x23e/0x336
[   14.709229]                                          [<ffffffff812ea755>] device_register+0x17/0x1c
[   14.709229]                                          [<ffffffff812ea80c>] device_create_vargs+0xb2/0xea
[   14.709229]                                          [<ffffffff812ea870>] device_create+0x2c/0x2e
[   14.709229]                                          [<ffffffff81b40717>] vtconsole_class_init+0x97/0xf3
[   14.709229]                                          [<ffffffff8100020a>] do_one_initcall+0x7a/0x130
[   14.709229]                                          [<ffffffff81b1cbec>] kernel_init+0xcd/0x147
[   14.709229]                                          [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   14.709229]     SOFTIRQ-ON-W at:
[   14.709229]                                          [<ffffffff8106c4e9>] mark_irqflags+0xf2/0x13e
[   14.709229]                                          [<ffffffff8106c760>] __lock_acquire+0x22b/0x3e2
[   14.709229]                                          [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   14.709229]                                          [<ffffffff815b9b41>] _raw_spin_lock+0x36/0x45
[   14.709229]                                          [<ffffffff81587e52>] klist_add_tail+0x20/0x3f
[   14.709229]                                          [<ffffffff812ea646>] device_add+0x23e/0x336
[   14.709229]                                          [<ffffffff812ea755>] device_register+0x17/0x1c
[   14.709229]                                          [<ffffffff812ea80c>] device_create_vargs+0xb2/0xea
[   14.709229]                                          [<ffffffff812ea870>] device_create+0x2c/0x2e
[   14.709229]                                          [<ffffffff81b40717>] vtconsole_class_init+0x97/0xf3
[   14.709229]                                          [<ffffffff8100020a>] do_one_initcall+0x7a/0x130
[   14.709229]                                          [<ffffffff81b1cbec>] kernel_init+0xcd/0x147
[   14.709229]                                          [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   14.709229]     INITIAL USE at:
[   14.709229]                                         [<ffffffff8106c77f>] __lock_acquire+0x24a/0x3e2
[   14.709229]                                         [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   14.709229]                                         [<ffffffff815b9b41>] _raw_spin_lock+0x36/0x45
[   14.709229]                                         [<ffffffff81587e52>] klist_add_tail+0x20/0x3f
[   14.709229]                                         [<ffffffff812ea646>] device_add+0x23e/0x336
[   14.709229]                                         [<ffffffff812ea755>] device_register+0x17/0x1c
[   14.709229]                                         [<ffffffff812ea80c>] device_create_vargs+0xb2/0xea
[   14.709229]                                         [<ffffffff812ea870>] device_create+0x2c/0x2e
[   14.709229]                                         [<ffffffff81b40717>] vtconsole_class_init+0x97/0xf3
[   14.709229]                                         [<ffffffff8100020a>] do_one_initcall+0x7a/0x130
[   14.709229]                                         [<ffffffff81b1cbec>] kernel_init+0xcd/0x147
[   14.709229]                                         [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   14.709229]   }
[   14.709229]   ... key      at: [<ffffffff8246d980>] __key.16100+0x0/0x680
[   14.709229]   ... acquired at:
[   14.709229]    [<ffffffff8106bdfa>] check_prevs_add+0x8b/0x104
[   15.001689]    [<ffffffff8106c1d4>] validate_chain+0x361/0x39d
[   15.001689]    [<ffffffff8106c89e>] __lock_acquire+0x369/0x3e2
[   15.001689]    [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   15.001689]    [<ffffffff815b9b41>] _raw_spin_lock+0x36/0x45
[   15.001689]    [<ffffffff81587a6d>] klist_next+0x21/0xd2
[   15.001689]    [<ffffffff812e94fb>] device_for_each_child+0x40/0x6a
[   15.001689]    [<ffffffff812f0a52>] rpm_suspend+0x27d/0x4f8
[   15.001689]    [<ffffffff812f18ba>] __pm_runtime_suspend+0x6c/0x89
[   15.001689]    [<ffffffff81304389>] scsi_runtime_idle+0x2b/0x2f
[   15.001689]    [<ffffffff812f0349>] __rpm_callback+0x2f/0x56
[   15.001689]    [<ffffffff812f0eb3>] rpm_idle+0x174/0x1db
[   15.001689]    [<ffffffff812f0fdd>] __pm_runtime_idle+0x6d/0x8a
[   15.001689]    [<ffffffff813044c2>] scsi_autopm_put_host+0x15/0x17
[   15.001689]    [<ffffffff812fcd22>] scsi_error_handler+0xb2/0x100
[   15.001689]    [<ffffffff8105965e>] kthread+0x7f/0x87
[   15.001689]    [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   15.001689] 
[   15.001689] -> (&(&dev->power.lock)->rlock){..-...} ops: 738 {
[   15.001689]    IN-SOFTIRQ-W at:
[   15.001689]                                        [<ffffffff8106c479>] mark_irqflags+0x82/0x13e
[   15.001689]                                        [<ffffffff8106c760>] __lock_acquire+0x22b/0x3e2
[   15.001689]                                        [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   15.001689]                                        [<ffffffff815b9c3a>] _raw_spin_lock_irqsave+0x44/0x57
[   15.001689]                                        [<ffffffff812f0cea>] pm_suspend_timer_fn+0x1d/0x72
[   15.001689]                                        [<ffffffff81048ee3>] call_timer_fn+0xb3/0x1f7
[   15.001689]                                        [<ffffffff810491d1>] run_timer_softirq+0x1aa/0x1f2
[   15.001689]                                        [<ffffffff81041da8>] __do_softirq+0x110/0x278
[   15.001689]                                        [<ffffffff81041fe0>] run_ksoftirqd+0xd0/0x20e
[   15.001689]                                        [<ffffffff8105965e>] kthread+0x7f/0x87
[   15.001689]                                        [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   15.001689]    INITIAL USE at:
[   15.001689]                                       [<ffffffff8106c77f>] __lock_acquire+0x24a/0x3e2
[   15.001689]                                       [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   15.001689]                                       [<ffffffff815b9c3a>] _raw_spin_lock_irqsave+0x44/0x57
[   15.001689]                                       [<ffffffff812f0fcf>] __pm_runtime_idle+0x5f/0x8a
[   15.001689]                                       [<ffffffff812ec6cf>] device_attach+0x88/0x9c
[   15.001689]                                       [<ffffffff812ebe27>] bus_probe_device+0x24/0x62
[   15.001689]                                       [<ffffffff812ea5f3>] device_add+0x1eb/0x336
[   15.001689]                                       [<ffffffff812ea755>] device_register+0x17/0x1c
[   15.001689]                                       [<ffffffff8127917f>] acpi_device_register+0x150/0x221
[   15.001689]                                       [<ffffffff81279412>] acpi_add_single_object+0x1c2/0x226
[   15.001689]                                       [<ffffffff81279514>] acpi_bus_check_add+0x9e/0xe6
[   15.001689]                                       [<ffffffff8127958f>] acpi_bus_scan+0x33/0x77
[   15.001689]                                       [<ffffffff81b3e736>] acpi_scan_init+0x4b/0xce
[   15.001689]                                       [<ffffffff81b3e59c>] acpi_init+0x9e/0xb4
[   15.001689]                                       [<ffffffff8100020a>] do_one_initcall+0x7a/0x130
[   15.001689]                                       [<ffffffff81b1cbec>] kernel_init+0xcd/0x147
[   15.001689]                                       [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   15.001689]  }
[   15.001689]  ... key      at: [<ffffffff8245b100>] __key.18419+0x0/0x8
[   15.001689]  ... acquired at:
[   15.001689]    [<ffffffff8106b67a>] check_usage_forwards+0x95/0xa6
[   15.001689]    [<ffffffff815984de>] mark_lock_irq+0x94/0x120
[   15.001689]    [<ffffffff8106c384>] mark_lock+0xaf/0x122
[   15.001689]    [<ffffffff8106c479>] mark_irqflags+0x82/0x13e
[   15.001689]    [<ffffffff8106c760>] __lock_acquire+0x22b/0x3e2
[   15.001689]    [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   15.001689]    [<ffffffff815b9c3a>] _raw_spin_lock_irqsave+0x44/0x57
[   15.001689]    [<ffffffff812f0cea>] pm_suspend_timer_fn+0x1d/0x72
[   15.001689]    [<ffffffff81048ee3>] call_timer_fn+0xb3/0x1f7
[   15.001689]    [<ffffffff810491d1>] run_timer_softirq+0x1aa/0x1f2
[   15.001689]    [<ffffffff81041da8>] __do_softirq+0x110/0x278
[   15.001689]    [<ffffffff81041fe0>] run_ksoftirqd+0xd0/0x20e
[   15.001689]    [<ffffffff8105965e>] kthread+0x7f/0x87
[   15.001689]    [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   15.001689] 
[   15.001689] 
[   15.001689] stack backtrace:
[   15.001689] Pid: 3, comm: ksoftirqd/0 Not tainted 3.2.0-rc3-next-20111128 #41
[   15.001689] Call Trace:
[   15.001689]  [<ffffffff8106b5d6>] print_irq_inversion_bug.part.16+0x19f/0x1ae
[   15.001689]  [<ffffffff8106b67a>] check_usage_forwards+0x95/0xa6
[   15.001689]  [<ffffffff8100366d>] ? dump_trace+0x201/0x219
[   15.001689]  [<ffffffff8106b5e5>] ? print_irq_inversion_bug.part.16+0x1ae/0x1ae
[   15.001689]  [<ffffffff815984de>] mark_lock_irq+0x94/0x120
[   15.001689]  [<ffffffff8106c384>] mark_lock+0xaf/0x122
[   15.001689]  [<ffffffff8106c479>] mark_irqflags+0x82/0x13e
[   15.001689]  [<ffffffff8106c760>] __lock_acquire+0x22b/0x3e2
[   15.001689]  [<ffffffff812f0cea>] ? pm_suspend_timer_fn+0x1d/0x72
[   15.001689]  [<ffffffff8106ce4d>] lock_acquire+0x103/0x153
[   15.001689]  [<ffffffff812f0cea>] ? pm_suspend_timer_fn+0x1d/0x72
[   15.001689]  [<ffffffff812f0ccd>] ? rpm_suspend+0x4f8/0x4f8
[   15.001689]  [<ffffffff812f0ccd>] ? rpm_suspend+0x4f8/0x4f8
[   15.001689]  [<ffffffff815b9c3a>] _raw_spin_lock_irqsave+0x44/0x57
[   15.001689]  [<ffffffff812f0cea>] ? pm_suspend_timer_fn+0x1d/0x72
[   18.416714]  [<ffffffff810491d9>] ? run_timer_softirq+0x1b2/0x1f2
[   18.416714]  [<ffffffff812f0cea>] pm_suspend_timer_fn+0x1d/0x72
[   18.416714]  [<ffffffff812f0ccd>] ? rpm_suspend+0x4f8/0x4f8
[   18.416714]  [<ffffffff812f0ccd>] ? rpm_suspend+0x4f8/0x4f8
[   18.416714]  [<ffffffff81048ee3>] call_timer_fn+0xb3/0x1f7
[   18.416714]  [<ffffffff81048e30>] ? del_timer+0x75/0x75
[   18.416714]  [<ffffffff812f0ccd>] ? rpm_suspend+0x4f8/0x4f8
[   18.416714]  [<ffffffff812f0ccd>] ? rpm_suspend+0x4f8/0x4f8
[   18.416714]  [<ffffffff810491d1>] run_timer_softirq+0x1aa/0x1f2
[   18.416714]  [<ffffffff81041da8>] __do_softirq+0x110/0x278
[   18.416714]  [<ffffffff81041fe0>] run_ksoftirqd+0xd0/0x20e
[   18.416714]  [<ffffffff81041f10>] ? __do_softirq+0x278/0x278
[   18.416714]  [<ffffffff8105965e>] kthread+0x7f/0x87
[   18.416714]  [<ffffffff815c1bf4>] kernel_thread_helper+0x4/0x10
[   18.416714]  [<ffffffff815ba7e1>] ? retint_restore_args+0xe/0xe
[   18.416714]  [<ffffffff810595df>] ? __init_kthread_worker+0x55/0x55
[   18.416714]  [<ffffffff815c1bf0>] ? gs_change+0xb/0xb

Attachment: pgpsAcZYvJG9C.pgp
Description: PGP signature


[Index of Archives]     [Linux IBM ACPI]     [Linux Power Management]     [Linux Kernel]     [Linux Laptop]     [Kernel Newbies]     [Share Photos]     [Security]     [Netfilter]     [Bugtraq]     [Yosemite News]     [MIPS Linux]     [ARM Linux]     [Linux Security]     [Linux RAID]     [Samba]     [Video 4 Linux]     [Device Mapper]     [Linux Resources]

  Powered by Linux