Message-Id: <201207062211.CBH48441.OSHVtQJFFMLOOF@I-love.SAKURA.ne.jp>
Date:	Fri, 6 Jul 2012 22:11:36 +0900
From:	Tetsuo Handa <penguin-kernel@...ove.SAKURA.ne.jp>
To:	linux-kernel@...r.kernel.org
Cc:	davem@...emloft.net
Subject: [linux-next-20120706] inconsistent lock state warning

Hello.

I got this warning upon boot. From where should I start debugging?

[    5.407664] ide0 at 0x170-0x177,0x376 on irq 15
[    5.413198] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports
[    5.414264] ide-gd driver 1.18
[    5.415129] ide-cd driver 5.00
[    5.419466]
[    5.419528] =================================
[    5.419590] [ INFO: inconsistent lock state ]
[    5.419653] 3.5.0-rc5-next-20120706 #51 Not tainted
[    5.419715] ---------------------------------
[    5.419777] inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage.
[    5.419840] swapper/0/1 [HC1[1]:SC0[0]:HE0:SE1] takes:
[    5.419902]  (&input_pool.lock){?.+...}, at: [<c1252880>] mix_pool_bytes.clone.0+0x20/0x50
[    5.421829] {HARDIRQ-ON-W} state was registered at:
[    5.421829]   [<c1077acc>] __lock_acquire+0x4fc/0x1390
[    5.421829]   [<c10789db>] lock_acquire+0x7b/0xa0
[    5.421829]   [<c13dfd06>] _raw_spin_lock+0x36/0x70
[    5.421829]   [<c12525f7>] account+0x27/0xf0
[    5.421829]   [<c1252aba>] extract_entropy+0x2a/0x70
[    5.421829]   [<c1252a30>] xfer_secondary_pool+0xd0/0x130
[    5.421829]   [<c1252aa7>] extract_entropy+0x17/0x70
[    5.421829]   [<c1252bc3>] get_random_bytes+0x23/0x30
[    5.421829]   [<c13327a3>] neigh_hash_alloc+0x63/0xa0
[    5.421829]   [<c13351cd>] neigh_table_init+0x6d/0x250
[    5.421829]   [<c15cd7bc>] arp_init+0x10/0x48
[    5.421829]   [<c15cdbe8>] inet_init+0x16c/0x269
[    5.421829]   [<c100110f>] do_one_initcall+0x2f/0x170
[    5.421829]   [<c159f53c>] kernel_init+0xec/0x18e
[    5.421829]   [<c13e13ba>] kernel_thread_helper+0x6/0xd
[    5.421829] irq event stamp: 2348792
[    5.421829] hardirqs last  enabled at (2348791): [<c13e0754>] restore_all_notrace+0x0/0x18
[    5.421829] hardirqs last disabled at (2348792): [<c13e13a7>] common_interrupt+0x27/0x34
[    5.421829] softirqs last  enabled at (2348758): [<c1037b71>] __do_softirq+0xe1/0x130
[    5.421829] softirqs last disabled at (2348743): [<c1004ce7>] do_softirq+0x87/0xd0
[    5.421829]
[    5.421829] other info that might help us debug this:
[    5.421829]  Possible unsafe locking scenario:
[    5.421829]
[    5.421829]        CPU0
[    5.421829]        ----
[    5.421829]   lock(&input_pool.lock);
[    5.421829]   <Interrupt>
[    5.421829]     lock(&input_pool.lock);
[    5.421829]
[    5.421829]  *** DEADLOCK ***
[    5.421829]
[    5.421829] 2 locks held by swapper/0/1:
[    5.421829]  #0:  (&__lockdep_no_validate__){......}, at: [<c125ce38>] __driver_attach+0x48/0x90
[    5.421829]  #1:  (&__lockdep_no_validate__){......}, at: [<c125ce44>] __driver_attach+0x54/0x90
[    5.421829]
[    5.421829] stack backtrace:
[    5.421829] Pid: 1, comm: swapper/0 Not tainted 3.5.0-rc5-next-20120706 #51
[    5.421829] Call Trace:
[    5.421829]  [<c1076038>] print_usage_bug+0x1f8/0x240
[    5.421829]  [<c1076e0d>] mark_lock+0x30d/0x3d0
[    5.421829]  [<c1073a6b>] ? trace_hardirqs_off+0xb/0x10
[    5.421829]  [<c1076080>] ? print_usage_bug+0x240/0x240
[    5.421829]  [<c1077e92>] __lock_acquire+0x8c2/0x1390
[    5.421829]  [<c11eab97>] ? __debug_check_no_obj_freed+0x117/0x180
[    5.421829]  [<c1073a6b>] ? trace_hardirqs_off+0xb/0x10
[    5.421829]  [<c1073a6b>] ? trace_hardirqs_off+0xb/0x10
[    5.421829]  [<c10d55c1>] ? kfree+0xc1/0xf0
[    5.421829]  [<c1106a08>] ? bio_kmalloc_destructor+0x8/0x10
[    5.421829]  [<c10789db>] lock_acquire+0x7b/0xa0
[    5.421829]  [<c1252880>] ? mix_pool_bytes.clone.0+0x20/0x50
[    5.421829]  [<c13dfe75>] _raw_spin_lock_irqsave+0x45/0x80
[    5.421829]  [<c1252880>] ? mix_pool_bytes.clone.0+0x20/0x50
[    5.421829]  [<c1252880>] mix_pool_bytes.clone.0+0x20/0x50
[    5.421829]  [<c105ec32>] ? sched_clock_local+0xb2/0x190
[    5.421829]  [<c1252ec2>] add_timer_randomness+0x52/0xd0
[    5.421829]  [<c1252f64>] add_disk_randomness+0x24/0x30
[    5.421829]  [<c11be7c0>] blk_update_bidi_request+0x50/0x80
[    5.421829]  [<c11be813>] blk_end_bidi_request+0x23/0x70
[    5.421829]  [<c11be8b2>] blk_end_request+0x12/0x20
[    5.421829]  [<c1270696>] ide_end_rq+0x26/0x50
[    5.421829]  [<c12706eb>] ide_complete_rq+0x2b/0x60
[    5.421829]  [<c127d942>] cdrom_newpc_intr+0x182/0x870
[    5.421829]  [<c127d7c0>] ? cdrom_analyze_sense_data+0x200/0x200
[    5.421829]  [<c127096b>] ide_intr+0x1ab/0x210
[    5.421829]  [<c1094dee>] handle_irq_event_percpu+0x3e/0x170
[    5.421829]  [<c1097bf1>] ? handle_edge_irq+0x11/0x100
[    5.421829]  [<c1094f4e>] ? handle_irq_event+0x2e/0x60
[    5.421829]  [<c1094f57>] handle_irq_event+0x37/0x60
[    5.421829]  [<c1097be0>] ? handle_level_irq+0xe0/0xe0
[    5.421829]  [<c1097c38>] handle_edge_irq+0x58/0x100
[    5.421829]  <IRQ>  [<c100406d>] ? do_IRQ+0x3d/0xb0
[    5.421829]  [<c13e13ae>] ? common_interrupt+0x2e/0x34
[    5.421829]  [<c1275b8f>] ? ide_output_data+0x9f/0x110
[    5.421829]  [<c127130f>] ? ide_set_handler+0x3f/0x50
[    5.421829]  [<c1276ea4>] ? ide_transfer_pc+0x194/0x270
[    5.421829]  [<c107739b>] ? trace_hardirqs_on+0xb/0x10
[    5.421829]  [<c1276b50>] ? ide_delayed_transfer_pc+0x30/0x30
[    5.421829]  [<c12712b2>] ? ide_execute_command+0x62/0x80
[    5.421829]  [<c1276d10>] ? ide_check_atapi_device+0x150/0x150
[    5.421829]  [<c127716b>] ? ide_issue_pc+0x1eb/0x270
[    5.421829]  [<c1276b50>] ? ide_delayed_transfer_pc+0x30/0x30
[    5.421829]  [<c127e170>] ? ide_cd_do_request+0x140/0x250
[    5.421829]  [<c1270e12>] ? do_ide_request+0x252/0x580
[    5.421829]  [<c107741c>] ? debug_check_no_locks_freed+0x7c/0x130
[    5.421829]  [<c11bbb87>] ? __blk_run_queue+0x17/0x20
[    5.421829]  [<c11bb187>] ? __elv_add_request+0x97/0x1d0
[    5.421829]  [<c11c25d3>] ? blk_execute_rq_nowait+0x33/0xd0
[    5.421829]  [<c11c25f7>] ? blk_execute_rq_nowait+0x57/0xd0
[    5.421829]  [<c11c2729>] ? blk_execute_rq+0xb9/0x130
[    5.421829]  [<c11c2570>] ? blk_rq_map_user+0x220/0x220
[    5.421829]  [<c1107bc7>] ? bio_copy_user_iov+0x2c7/0x3a0
[    5.421829]  [<c127e370>] ? ide_cd_queue_pc+0xc0/0x1a0
[    5.421829]  [<c127f1ec>] ? ide_cdrom_packet+0xac/0x110
[    5.421829]  [<c12cd910>] ? cdrom_mode_sense+0x50/0x60
[    5.421829]  [<c127d1f3>] ? ide_cdrom_get_capabilities+0x53/0x70
[    5.421829]  [<c127eda2>] ? ide_cd_probe+0x302/0x600
[    5.421829]  [<c1136001>] ? sysfs_addrm_finish+0x11/0x50
[    5.421829]  [<c1136da9>] ? sysfs_do_create_link+0xb9/0x1f0
[    5.421829]  [<c1055753>] ? __blocking_notifier_call_chain+0xa3/0xb0
[    5.421829]  [<c126f78f>] ? generic_ide_probe+0x1f/0x30
[    5.421829]  [<c125cc7f>] ? driver_probe_device+0x6f/0x1e0
[    5.421829]  [<c125ce71>] ? __driver_attach+0x81/0x90
[    5.421829]  [<c125b558>] ? bus_for_each_dev+0x48/0x70
[    5.421829]  [<c125ca29>] ? driver_attach+0x19/0x20
[    5.421829]  [<c125cdf0>] ? driver_probe_device+0x1e0/0x1e0
[    5.421829]  [<c125c47e>] ? bus_add_driver+0xae/0x250
[    5.421829]  [<c126f7a0>] ? generic_ide_probe+0x30/0x30
[    5.421829]  [<c126f7a0>] ? generic_ide_probe+0x30/0x30
[    5.421829]  [<c125d315>] ? driver_register+0x65/0x110
[    5.421829]  [<c15c3508>] ? ide_cdrom_init+0x1c/0x1e
[    5.421829]  [<c100110f>] ? do_one_initcall+0x2f/0x170
[    5.421829]  [<c15c34ec>] ? ide_gd_init+0x1e/0x1e
[    5.421829]  [<c159f53c>] ? kernel_init+0xec/0x18e
[    5.421829]  [<c159f3f5>] ? parse_early_options+0x35/0x35
[    5.421829]  [<c159f450>] ? repair_env_string+0x5b/0x5b
[    5.421829]  [<c13e13ba>] ? kernel_thread_helper+0x6/0xd
[    5.435452] ide-cd: hda: ATAPI 1X CD-ROM drive, 32kB Cache
[    5.435514] cdrom: Uniform CD-ROM driver Revision: 3.20
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/

Powered by blists - more mailing lists