Re: 2.6.25-rc9 -- INFO: possible circular locking dependency detected

[Peter Zijlstra <peterz@infradead.org>]

Fun,

I will need to sort out this code before I can say anything about that,
perhaps Gautham and or Rafael have ideas before I can come up with
something.. ?

On Sun, 2008-04-13 at 23:04 -0400, Miles Lane wrote:
> [ 3217.586003] [ INFO: possible circular locking dependency detected ]
> [ 3217.586006] 2.6.25-rc9 #1
> [ 3217.586008] -------------------------------------------------------
> [ 3217.586011] pm-suspend/7421 is trying to acquire lock:
> [ 3217.586013]  (&per_cpu(cpu_policy_rwsem, cpu)){----}, at:
> [<c0277fe1>] lock_policy_rwsem_write+0x33/0x5a
> [ 3217.586023]
> [ 3217.586024] but task is already holding lock:
> [ 3217.586026]  (&cpu_hotplug.lock){--..}, at: [<c0142cec>]
> cpu_hotplug_begin+0x2f/0x89
> [ 3217.586033]
> [ 3217.586033] which lock already depends on the new lock.
> [ 3217.586035]
> [ 3217.586036]
> [ 3217.586037] the existing dependency chain (in reverse order) is:
> [ 3217.586039]
> [ 3217.586040] -> #3 (&cpu_hotplug.lock){--..}:
> [ 3217.586044]        [<c013f39f>] __lock_acquire+0xa02/0xbaf
> [ 3217.586052]        [<c013f5c2>] lock_acquire+0x76/0x9d
> [ 3217.586058]        [<c0309c17>] mutex_lock_nested+0xd5/0x274
> [ 3217.586066]        [<c0143038>] get_online_cpus+0x2c/0x3e
> [ 3217.586072]        [<c011eb03>] sched_getaffinity+0xe/0x4d
> [ 3217.586079]        [<c0159784>] __synchronize_sched+0x11/0x5f
> [ 3217.586087]        [<c0137380>] synchronize_srcu+0x22/0x5b
> [ 3217.586093]        [<c01376a3>] srcu_notifier_chain_unregister+0x45/0x4c
> [ 3217.586100]        [<c0277204>] cpufreq_unregister_notifier+0x1f/0x2f
> [ 3217.586107]        [<f8cfd68c>] cpufreq_governor_dbs+0x1e9/0x242
> [cpufreq_conservative]
> [ 3217.586117]        [<c027758d>] __cpufreq_governor+0xb2/0xe9
> [ 3217.586124]        [<c0277703>] __cpufreq_set_policy+0x13f/0x1c3
> [ 3217.586130]        [<c0277bf3>] store_scaling_governor+0x150/0x17f
> [ 3217.586137]        [<c0278708>] store+0x42/0x5b
> [ 3217.586143]        [<c01b1ac9>] sysfs_write_file+0xb8/0xe3
> [ 3217.586151]        [<c017dbc7>] vfs_write+0x8c/0x108
> [ 3217.586158]        [<c017e122>] sys_write+0x3b/0x60
> [ 3217.586165]        [<c0104470>] sysenter_past_esp+0x6d/0xc5
> [ 3217.586172]        [<ffffffff>] 0xffffffff
> [ 3217.586184]
> [ 3217.586185] -> #2 (&sp->mutex){--..}:
> [ 3217.586188]        [<c013f39f>] __lock_acquire+0xa02/0xbaf
> [ 3217.586195]        [<c013f5c2>] lock_acquire+0x76/0x9d
> [ 3217.586201]        [<c0309c17>] mutex_lock_nested+0xd5/0x274
> [ 3217.586208]        [<c0137374>] synchronize_srcu+0x16/0x5b
> [ 3217.586214]        [<c01376a3>] srcu_notifier_chain_unregister+0x45/0x4c
> [ 3217.586220]        [<c0277204>] cpufreq_unregister_notifier+0x1f/0x2f
> [ 3217.586227]        [<f8cfd68c>] cpufreq_governor_dbs+0x1e9/0x242
> [cpufreq_conservative]
> [ 3217.586235]        [<c027758d>] __cpufreq_governor+0xb2/0xe9
> [ 3217.586242]        [<c0277703>] __cpufreq_set_policy+0x13f/0x1c3
> [ 3217.586248]        [<c0277bf3>] store_scaling_governor+0x150/0x17f
> [ 3217.586255]        [<c0278708>] store+0x42/0x5b
> [ 3217.586261]        [<c01b1ac9>] sysfs_write_file+0xb8/0xe3
> [ 3217.586268]        [<c017dbc7>] vfs_write+0x8c/0x108
> [ 3217.586274]        [<c017e122>] sys_write+0x3b/0x60
> [ 3217.586280]        [<c0104470>] sysenter_past_esp+0x6d/0xc5
> [ 3217.586287]        [<ffffffff>] 0xffffffff
> [ 3217.586297]
> [ 3217.586298] -> #1 (dbs_mutex#2){--..}:
> [ 3217.586302]        [<c013f39f>] __lock_acquire+0xa02/0xbaf
> [ 3217.586309]        [<c013f5c2>] lock_acquire+0x76/0x9d
> [ 3217.586315]        [<c0309c17>] mutex_lock_nested+0xd5/0x274
> [ 3217.586322]        [<f8cfd511>] cpufreq_governor_dbs+0x6e/0x242
> [cpufreq_conservative]
> [ 3217.586330]        [<c027758d>] __cpufreq_governor+0xb2/0xe9
> [ 3217.586336]        [<c0277719>] __cpufreq_set_policy+0x155/0x1c3
> [ 3217.586343]        [<c0277bf3>] store_scaling_governor+0x150/0x17f
> [ 3217.586349]        [<c0278708>] store+0x42/0x5b
> [ 3217.586355]        [<c01b1ac9>] sysfs_write_file+0xb8/0xe3
> [ 3217.586362]        [<c017dbc7>] vfs_write+0x8c/0x108
> [ 3217.586369]        [<c017e122>] sys_write+0x3b/0x60
> [ 3217.586375]        [<c0104470>] sysenter_past_esp+0x6d/0xc5
> [ 3217.586381]        [<ffffffff>] 0xffffffff
> [ 3217.586451]
> [ 3217.586452] -> #0 (&per_cpu(cpu_policy_rwsem, cpu)){----}:
> [ 3217.586456]        [<c013f2c6>] __lock_acquire+0x929/0xbaf
> [ 3217.586463]        [<c013f5c2>] lock_acquire+0x76/0x9d
> [ 3217.586469]        [<c030a219>] down_write+0x28/0x44
> [ 3217.586475]        [<c0277fe1>] lock_policy_rwsem_write+0x33/0x5a
> [ 3217.586482]        [<c0308abd>] cpufreq_cpu_callback+0x43/0x66
> [ 3217.586489]        [<c013753e>] notifier_call_chain+0x2b/0x4a
> [ 3217.586495]        [<c013757f>] __raw_notifier_call_chain+0xe/0x10
> [ 3217.586501]        [<c0142dde>] _cpu_down+0x71/0x1f8
> [ 3217.586507]        [<c0143094>] disable_nonboot_cpus+0x4a/0xc6
> [ 3217.586513]        [<c0146ce5>] suspend_devices_and_enter+0x6c/0x101
> [ 3217.586521]        [<c0146e8b>] enter_state+0xc4/0x119
> [ 3217.586527]        [<c0146f76>] state_store+0x96/0xac
> [ 3217.586533]        [<c01e7479>] kobj_attr_store+0x1a/0x22
> [ 3217.586541]        [<c01b1ac9>] sysfs_write_file+0xb8/0xe3
> [ 3217.586547]        [<c017dbc7>] vfs_write+0x8c/0x108
> [ 3217.586554]        [<c017e122>] sys_write+0x3b/0x60
> [ 3217.586560]        [<c0104470>] sysenter_past_esp+0x6d/0xc5
> [ 3217.586567]        [<ffffffff>] 0xffffffff
> [ 3217.586578]
> [ 3217.586578] other info that might help us debug this:
> [ 3217.586580]
> [ 3217.586582] 5 locks held by pm-suspend/7421:
> [ 3217.586584]  #0:  (&buffer->mutex){--..}, at: [<c01b1a36>]
> sysfs_write_file+0x25/0xe3
> [ 3217.586590]  #1:  (pm_mutex){--..}, at: [<c0146eca>] enter_state+0x103/0x119
> [ 3217.586596]  #2:  (pm_sleep_rwsem){--..}, at: [<c0261789>]
> device_suspend+0x25/0x1ad
> [ 3217.586604]  #3:  (cpu_add_remove_lock){--..}, at: [<c0142c93>]
> cpu_maps_update_begin+0xf/0x11
> [ 3217.586610]  #4:  (&cpu_hotplug.lock){--..}, at: [<c0142cec>]
> cpu_hotplug_begin+0x2f/0x89
> [ 3217.586616]
> [ 3217.586617] stack backtrace:
> [ 3217.586620] Pid: 7421, comm: pm-suspend Not tainted 2.6.25-rc9 #1
> [ 3217.586627]  [<c013d914>] print_circular_bug_tail+0x5b/0x66
> [ 3217.586634]  [<c013d25e>] ? print_circular_bug_entry+0x39/0x43
> [ 3217.586643]  [<c013f2c6>] __lock_acquire+0x929/0xbaf
> [ 3217.586656]  [<c013f5c2>] lock_acquire+0x76/0x9d
> [ 3217.586661]  [<c0277fe1>] ? lock_policy_rwsem_write+0x33/0x5a
> [ 3217.586668]  [<c030a219>] down_write+0x28/0x44
> [ 3217.586673]  [<c0277fe1>] ? lock_policy_rwsem_write+0x33/0x5a
> [ 3217.586678]  [<c0277fe1>] lock_policy_rwsem_write+0x33/0x5a
> [ 3217.586684]  [<c0308abd>] cpufreq_cpu_callback+0x43/0x66
> [ 3217.586689]  [<c013753e>] notifier_call_chain+0x2b/0x4a
> [ 3217.586696]  [<c013757f>] __raw_notifier_call_chain+0xe/0x10
> [ 3217.586701]  [<c0142dde>] _cpu_down+0x71/0x1f8
> [ 3217.586710]  [<c0143094>] disable_nonboot_cpus+0x4a/0xc6
> [ 3217.586716]  [<c0146ce5>] suspend_devices_and_enter+0x6c/0x101
> [ 3217.586721]  [<c0146e8b>] enter_state+0xc4/0x119
> [ 3217.586726]  [<c0146f76>] state_store+0x96/0xac
> [ 3217.586731]  [<c0146ee0>] ? state_store+0x0/0xac
> [ 3217.586736]  [<c01e7479>] kobj_attr_store+0x1a/0x22
> [ 3217.586742]  [<c01b1ac9>] sysfs_write_file+0xb8/0xe3
> [ 3217.586750]  [<c01b1a11>] ? sysfs_write_file+0x0/0xe3
> [ 3217.586755]  [<c017dbc7>] vfs_write+0x8c/0x108
> [ 3217.586762]  [<c017e122>] sys_write+0x3b/0x60
> [ 3217.586769]  [<c0104470>] sysenter_past_esp+0x6d/0xc5
> [ 3217.586780]  =======================
> [ 3217.588064] Breaking affinity for irq 16
> --
> To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html
> Please read the FAQ at  http://www.tux.org/lkml/