Hello,I get the following quite regularly when suspending directly (~1min) after boot. Apart from the message, I have not observed any ill effects. I'm running Debian lenny on vanilla 2.6.30 (with a minimal ath5k patch) on an Eee PC 1000HG. Full dmesg output is attached.
Kind regards, Thiemo [ 60.853557] ======================================================= [ 60.853778] [ INFO: possible circular locking dependency detected ] [ 60.853893] 2.6.30 #3 [ 60.853997] ------------------------------------------------------- [ 60.854016] 94cpufreq/3046 is trying to acquire lock:[ 60.854016] (&(&dbs_info->work)->work){+.+...}, at: [<c103774c>] __cancel_work_timer+0x8f/0x190
[ 60.854016] [ 60.854016] but task is already holding lock:[ 60.854016] (dbs_mutex){+.+.+.}, at: [<c11bfcfa>] cpufreq_governor_dbs+0x1f6/0x278
[ 60.854016]
[ 60.854016] which lock already depends on the new lock.
[ 60.854016]
[ 60.854016]
[ 60.854016] the existing dependency chain (in reverse order) is:
[ 60.854016]
[ 60.854016] -> #2 (dbs_mutex){+.+.+.}:
[ 60.854016] [<c1048e34>] __lock_acquire+0x99d/0xb0c
[ 60.854016] [<c104904b>] lock_acquire+0xa8/0xc5
[ 60.854016] [<c124c3ed>] __mutex_lock_common+0x3e/0x37c
[ 60.854016] [<c124c7b4>] mutex_lock_nested+0x29/0x31
[ 60.854016] [<c11bfb78>] cpufreq_governor_dbs+0x74/0x278
[ 60.854016] [<c11bdbf9>] __cpufreq_governor+0x66/0x9d
[ 60.854016] [<c11bddae>] __cpufreq_set_policy+0x155/0x1c3
[ 60.854016] [<c11bef83>] cpufreq_add_dev+0x4f5/0x618
[ 60.854016] [<c11907d1>] sysdev_driver_register+0x96/0xe5
[ 60.854016] [<c11bdeba>] cpufreq_register_driver+0x9e/0x161
[ 60.854016] [<f856e0b6>] 0xf856e0b6
[ 60.854016] [<c1001137>] do_one_initcall+0x4a/0x111
[ 60.854016] [<c1050816>] sys_init_module+0x89/0x191
[ 60.854016] [<c1002f24>] sysenter_do_call+0x12/0x32
[ 60.854016] [<ffffffff>] 0xffffffff
[ 60.854016]
[ 60.854016] -> #1 (&per_cpu(cpu_policy_rwsem, cpu)){+++++.}:
[ 60.854016] [<c1048e34>] __lock_acquire+0x99d/0xb0c
[ 60.854016] [<c104904b>] lock_acquire+0xa8/0xc5
[ 60.854016] [<c124ca0c>] down_write+0x2a/0x39
[ 60.854016] [<c11bea31>] lock_policy_rwsem_write+0x33/0x5b
[ 60.854016] [<c11bf8f7>] do_dbs_timer+0x4b/0x258
[ 60.854016] [<c1037062>] worker_thread+0x1c5/0x2a2
[ 60.854016] [<c103a286>] kthread+0x45/0x6b
[ 60.854016] [<c1003a3b>] kernel_thread_helper+0x7/0x10
[ 60.854016] [<ffffffff>] 0xffffffff
[ 60.854016]
[ 60.854016] -> #0 (&(&dbs_info->work)->work){+.+...}:
[ 60.854016] [<c1048d46>] __lock_acquire+0x8af/0xb0c
[ 60.854016] [<c104904b>] lock_acquire+0xa8/0xc5
[ 60.854016] [<c1037772>] __cancel_work_timer+0xb5/0x190
[ 60.854016] [<c1037858>] cancel_delayed_work_sync+0xb/0xd
[ 60.854016] [<c11bfd09>] cpufreq_governor_dbs+0x205/0x278
[ 60.854016] [<c11bdbf9>] __cpufreq_governor+0x66/0x9d
[ 60.854016] [<c11bdd98>] __cpufreq_set_policy+0x13f/0x1c3
[ 60.854016] [<c11be5fb>] store_scaling_governor+0x197/0x1bf
[ 60.854016] [<c11bf0ee>] store+0x48/0x61
[ 60.854016] [<c10ddc4e>] sysfs_write_file+0xb9/0xe4
[ 60.854016] [<c109e025>] vfs_write+0x84/0xdf
[ 60.854016] [<c109e119>] sys_write+0x3b/0x60
[ 60.854016] [<c1002f24>] sysenter_do_call+0x12/0x32
[ 60.854016] [<ffffffff>] 0xffffffff
[ 60.854016]
[ 60.854016] other info that might help us debug this:
[ 60.854016]
[ 60.854016] 3 locks held by 94cpufreq/3046:
[ 60.854016] #0: (&buffer->mutex){+.+.+.}, at: [<c10ddbba>]
sysfs_write_file+0x25/0xe4
[ 60.854016] #1: (&per_cpu(cpu_policy_rwsem, cpu)){+++++.}, at:
[<c11bea31>] lock_policy_rwsem_write+0x33/0x5b
[ 60.854016] #2: (dbs_mutex){+.+.+.}, at: [<c11bfcfa>]
cpufreq_governor_dbs+0x1f6/0x278
[ 60.854016] [ 60.854016] stack backtrace: [ 60.854016] Pid: 3046, comm: 94cpufreq Not tainted 2.6.30 #3 [ 60.854016] Call Trace: [ 60.854016] [<c124b17f>] ? printk+0xf/0x18 [ 60.854016] [<c10481d3>] print_circular_bug_tail+0x5d/0x68 [ 60.854016] [<c1048d46>] __lock_acquire+0x8af/0xb0c [ 60.854016] [<c104904b>] lock_acquire+0xa8/0xc5 [ 60.854016] [<c103774c>] ? __cancel_work_timer+0x8f/0x190 [ 60.854016] [<c1037772>] __cancel_work_timer+0xb5/0x190 [ 60.854016] [<c103774c>] ? __cancel_work_timer+0x8f/0x190 [ 60.854016] [<c1047c9a>] ? trace_hardirqs_on_caller+0xff/0x120 [ 60.854016] [<c124c6dd>] ? __mutex_lock_common+0x32e/0x37c [ 60.854016] [<c1037858>] cancel_delayed_work_sync+0xb/0xd [ 60.854016] [<c11bfd09>] cpufreq_governor_dbs+0x205/0x278 [ 60.854016] [<c11bdbf9>] __cpufreq_governor+0x66/0x9d [ 60.854016] [<c11bdd98>] __cpufreq_set_policy+0x13f/0x1c3 [ 60.854016] [<c11be5fb>] store_scaling_governor+0x197/0x1bf [ 60.854016] [<c11bf20e>] ? handle_update+0x0/0x28 [ 60.854016] [<c11bea31>] ? lock_policy_rwsem_write+0x33/0x5b [ 60.854016] [<c11be464>] ? store_scaling_governor+0x0/0x1bf [ 60.854016] [<c11bf0ee>] store+0x48/0x61 [ 60.854016] [<c10ddc4e>] sysfs_write_file+0xb9/0xe4 [ 60.854016] [<c10ddb95>] ? sysfs_write_file+0x0/0xe4 [ 60.854016] [<c109e025>] vfs_write+0x84/0xdf [ 60.854016] [<c109e119>] sys_write+0x3b/0x60 [ 60.854016] [<c1002f24>] sysenter_do_call+0x12/0x32 [ 61.214701] PM: Syncing filesystems ... done. [ 61.217330] PM: Preparing system for mem sleep[ 61.221334] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 61.222047] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[ 61.222468] PM: Entering mem sleep [ 61.222548] Suspending console(s) (use no_console_suspend to debug) [ 61.223404] pci 0000:00:02.0: PCI INT A disabled [ 61.234493] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 61.475261] sd 0:0:0:0: [sda] Stopping disk [ 62.377646] ACPI handle has no context! [ 62.377661] ATL1E 0000:03:00.0: PME# disabled [ 62.377676] ATL1E 0000:03:00.0: PCI INT A disabled [ 62.377689] ACPI handle has no context! [ 62.388545] ata_piix 0000:00:1f.2: PCI INT B disabled [ 62.399214] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 62.399329] ehci_hcd 0000:00:1d.7: PME# disabled [ 62.399341] ehci_hcd 0000:00:1d.7: wakeup: 0 [ 62.410211] ehci_hcd 0000:00:1d.7: --> PCI D3 [ 62.421241] HDA Intel 0000:00:1b.0: PCI INT A disabled [ 62.433341] ACPI: Preparing to enter system sleep state S3 [ 62.691807] Disabling non-boot CPUs ... [ 62.795034] CPU 1 is now offline [ 62.795039] lockdep: fixing up alternatives. [ 62.795047] SMP alternatives: switching to UP code [ 62.798179] CPU0 attaching NULL sched-domain. [ 62.798188] CPU1 attaching NULL sched-domain. [ 62.798210] CPU0 attaching NULL sched-domain. [ 62.798897] CPU1 is down[ 62.798936] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[ 62.798936] Intel machine check architecture supported. [ 62.798936] Intel machine check reporting enabled on CPU#0. [ 62.798936] Back to C! [ 62.799104] Enabling non-boot CPUs ... -- +-----------------------------------+--------------------------+ | Dipl.-Phys. Thiemo Nagel | | | Technische Universitaet Muenchen | Room PH1 3276 | | Physik-Department E18 | | | James-Franck-Strasse | Phone +49 89 289-12379 | | D-85747 Garching | Fax +49 89 289-12570 | +-----------------------------------+--------------------------+
Attachment:
circular_lock.bz2
Description: application/bzip
