pandaboard linaro ubuntu 12.03 kernel: BUG: bad unlock balance detected

[Chris Bainbridge]

Using linaro ubuntu 12.03, boot log:

Begin: Running /scripts/init-bottom ... done.
[ 8.555664] (stk) :line disc installation timed out
[ 8.561889] (stk) :ldisc_install = 0
[ 9.679534] (stk) :ldisc_install = 1[ 10.680694] (stk) :line disc installation timed out
[ 10.792144] platform_mem_setup : status [0x0]
[ 10.802978] platform_setup : status [0x0]
[ 10.812316] proc_mgr_setup : status [0x0]
[ 10.821838] sharedregion_setup : status [0x0]
[ 10.832519] notify_ducatidrv_setup : status [0x0]
[ 10.842315] notify_setup : status [0x0]
[ 10.851562] ipu_pm_setup : status [0x0]
[ 10.860321] nameserver_setup : status [0x0]
[ 10.869720] nameserver_get_handle failed! status = 0xfffffffe
[ 10.875946] gatemp_setup : status [0x0]
[ 10.889068] gatepeterson_setup : status [0x0]
[ 10.898742] nameserver_get_handle failed! status = 0xfffffffe
[ 10.904998] messageq_setup : status [0x0]
[ 10.918395] nameserver_get_handle failed! status = 0xfffffffe
[ 10.924591] heapbufmp_setup : status [0x0]
[ 10.937927] nameserver_get_handle failed! status = 0xfffffffe
[ 10.944122] heapmemmp_setup : status [0x0]
[ 10.957366] nameserver_get_handle failed! status = 0xfffffffe
[ 10.963562] listmp_setup : status [0x0]
[ 10.976318] transportshm_setup : status [0xa7a000]
[ 10.985717] nameserver_remotenotify_setup : status [0x0]
[ 10.996673] _platform_setup : status [0x0]
[ 11.508789] proc4430_attach num_mem_entries = 6
[ 11.524322] proc4430_attach num_mem_entries = 6
[ 11.942321] _platform_read_slave_memory successful! status = 0x0, proc_id = 2, addr = 0x12e00, m_addr = 0xf1012e00,0
[ 11.964904] _platform_read_slave_memory successful! status = 0x0, proc_id = 2, addr = 0x12e20, m_addr = 0xf1012e20,0
[ 11.992797] _platform_write_slave_memory successful! status = 0x0, proc_id = 2, addr = 0x12e00, m_addr = 0xf1012e000
[ 12.031860] gatehwspinlock_enter failed! status = 0xffffffed
[ 12.038055] =====================================
[ 12.054138] [ BUG: bad unlock balance detected! ]
[ 12.064605] -------------------------------------
[ 12.074951] syslink_daemon/1114 is trying to release lock (gatemp_module->gate_mutex) at:
[ 12.089172] [<c0739410>] mutex_unlock+0x18/0x1c
[ 12.099517] but there are no more locks to release!
[ 12.110412]
[ 12.110412] other info that might help us debug this:
[ 12.128356] no locks held by syslink_daemon/1114.
[ 12.138885]
[ 12.138885] stack backtrace:
[ 12.154144] [<c001b5f0>] (unwind_backtrace+0x0/0xec) from [<c0716ce8>] (dump_stack+0x20/0x24)
[ 12.168609] [<c0716ce8>] (dump_stack+0x20/0x24) from [<c071a24c>] (print_unlock_inbalance_bug.part.19+0x84/0xac)
[ 12.185089] [<c071a24c>] (print_unlock_inbalance_bug.part.19+0x84/0xac) from [<c0094840>] (print_unlock_inbalance_b)
[ 12.208953] [<c0094840>] (print_unlock_inbalance_bug+0x44/0x50) from [<c0097388>] (__lock_release+0x9c/0xd8)
[ 12.225677] [<c0097388>] (__lock_release+0x9c/0xd8) from [<c00974a0>] (lock_release+0xdc/0x100)
[ 12.241241] [<c00974a0>] (lock_release+0xdc/0x100) from [<c0739364>] (__mutex_unlock_slowpath+0xf8/0x18c)
[ 12.257873] [<c0739364>] (__mutex_unlock_slowpath+0xf8/0x18c) from [<c0739410>] (mutex_unlock+0x18/0x1c)
[ 12.274383] [<c0739410>] (mutex_unlock+0x18/0x1c) from [<c053e734>] (gatehwspinlock_leave+0xc0/0xe0)
[ 12.290740] [<c053e734>] (gatehwspinlock_leave+0xc0/0xe0) from [<c053f6d0>] (gatemp_leave+0x28/0x2c)
[ 12.307037] [<c053f6d0>] (gatemp_leave+0x28/0x2c) from [<c0541d28>] (heapmemmp_delete+0x12c/0x1d4)
[ 12.323364] [<c0541d28>] (heapmemmp_delete+0x12c/0x1d4) from [<c053bf30>] (sharedregion_stop+0xd8/0x14c)
[ 12.340332] [<c053bf30>] (sharedregion_stop+0xd8/0x14c) from [<c054acac>] (ipc_stop+0x118/0x16c)
[ 12.356536] [<c054acac>] (ipc_stop+0x118/0x16c) from [<c054ae98>] (ipc_destroy+0x80/0xbc)
[ 12.372039] [<c054ae98>] (ipc_destroy+0x80/0xbc) from [<c054b158>] (sysipc_ioctl+0x178/0x3e0)
[ 12.388397] [<c054b158>] (sysipc_ioctl+0x178/0x3e0) from [<c054b5d0>] (ipc_ioc_router+0x140/0x194)
[ 12.404937] [<c054b5d0>] (ipc_ioc_router+0x140/0x194) from [<c054b68c>] (ipc_ioctl+0x68/0x74)
[ 12.420959] [<c054b68c>] (ipc_ioctl+0x68/0x74) from [<c0127af4>] (do_vfs_ioctl+0x2a8/0x2e4)
[ 12.436920] [<c0127af4>] (do_vfs_ioctl+0x2a8/0x2e4) from [<c0127b90>] (sys_ioctl+0x60/0x84)
[ 12.452941] [<c0127b90>] (sys_ioctl+0x60/0x84) from [<c00130e0>] (ret_fast_syscall+0x0/0x3c)
[ 12.469207] gatehwspinlock_enter failed! status = 0xffffffed
[ 12.475311] gatemp_clear_region0_reserved: either nothing to do or not implemented
[ 12.475341] Unable to handle kernel paging request at virtual address f0a80388
[ 12.475341] pgd = ebb84000
[ 12.475341] [f0a80388] *pgd=abc02811, *pte=00000000, *ppte=00000000
[ 12.475372] Internal error: Oops: 807 [#1] PREEMPT SMP
[ 12.475402] Modules linked in:
[ 12.475402] CPU: 1 Tainted: G W (3.1.1-26-linaro-lt-omap #26~lt~ci~20120325001352+1332635991~4f6ec49b-)
[ 12.475433] PC is at gatemp_instance_finalize.isra.1+0x50/0x1b4
[ 12.475463] LR is at gatemp_delete+0xe0/0x118
[ 12.475463] pc : [<c053f724>] lr : [<c05407f0>] psr: 800f0113
[ 12.475494] sp : ebb9be18 ip : ebb9be48 fp : ebb9be44
[ 12.475494] r10: 00000000 r9 : ebb9a000 r8 : 00000000
[ 12.475494] r7 : eeabf7e0 r6 : 00000001 r5 : c0b666dc r4 : ebbf16a0
[ 12.475524] r3 : f0a80380 r2 : 00000000 r1 : 00000000 r0 : ebbf16a0
[ 12.475524] Flags: Nzcv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user
[ 12.475555] Control: 10c5387d Table: abb8404a DAC: 00000015
[ 12.475555] Process syslink_daemon (pid: 1114, stack limit = 0xebb9a2f8)
[ 12.475555] Stack: (0xebb9be18 to 0xebb9c000)
[ 12.475585] be00: eeabf7e0 00000000
[ 12.475585] be20: ebb9a000 00000000 ebb9be4c 00000000 c0b666dc 00000001 ebb9be5c ebb9be48
[ 12.475616] be40: c05407f0 c053f6e0 c0b66684 00000080 ebb9be74 ebb9be60 c054085c c054071c
[ 12.475646] be60: 00000000 00000000 ebb9bebc ebb9be78 c054acb8 c0540834 eeabf7e8 f0a80000
[ 12.475646] be80: 00054000 00010003 00000080 00000001 00000000 00000000 00000000 00000000
[ 12.475677] bea0: 00000002 00000001 bec04ab0 ebb65dc0 ebb9bed4 ebb9bec0 c054ae98 c054aba0
[ 12.475677] bec0: 00000001 bec04ab0 ebb9bf14 ebb9bed8 c054b158 c054ae24 bec04af0 00000000
[ 12.475708] bee0: 00000000 b6fe648c 0ca11000 00000000 bec04ab0 bec04ab0 c014e083 00000003
[ 12.475738] bf00: 00000000 00000000 ebb9bf34 ebb9bf18 c054b5d0 c054afec 00000001 c0134678
[ 12.475738] bf20: 00000020 ffc04ac3 ebb9bf4c ebb9bf38 c054b68c c054b49c c054b624 ee683f00
[ 12.475769] bf40: ebb9bf74 ebb9bf50 c0127af4 c054b630 00000000 c0316180 ee683f00 bec04ab0
[ 12.475769] bf60: c014e083 00000003 ebb9bfa4 ebb9bf78 c0127b90 c0127858 ebb9bf94 00000000
[ 12.475799] bf80: c0098020 b6f90000 bec04c98 00009419 00000036 c0013368 00000000 ebb9bfa8
[ 12.475830] bfa0: c00130e0 c0127b3c b6f90000 bec04c98 00000003 c014e083 bec04ab0 00000003
[ 12.475830] bfc0: b6f90000 bec04c98 00009419 00000036 00000000 00000000 b6ff6000 00000000
[ 12.475860] bfe0: b6f90038 bec04a54 b6f77d63 b6ef92cc 200f0110 00000003 affee821 affeec21
[ 12.475891] [<c053f724>] (gatemp_instance_finalize.isra.1+0x50/0x1b4) from [<c05407f0>] (gatemp_delete+0xe0/0x118)
[ 12.475921] [<c05407f0>] (gatemp_delete+0xe0/0x118) from [<c054085c>] (gatemp_stop+0x34/0x4c)
[ 12.475952] [<c054085c>] (gatemp_stop+0x34/0x4c) from [<c054acb8>] (ipc_stop+0x124/0x16c)
[ 12.475952] [<c054acb8>] (ipc_stop+0x124/0x16c) from [<c054ae98>] (ipc_destroy+0x80/0xbc)
[ 12.475982] [<c054ae98>] (ipc_destroy+0x80/0xbc) from [<c054b158>] (sysipc_ioctl+0x178/0x3e0)
[ 12.476013] [<c054b158>] (sysipc_ioctl+0x178/0x3e0) from [<c054b5d0>] (ipc_ioc_router+0x140/0x194)
[ 12.476043] [<c054b5d0>] (ipc_ioc_router+0x140/0x194) from [<c054b68c>] (ipc_ioctl+0x68/0x74)
[ 12.476043] [<c054b68c>] (ipc_ioctl+0x68/0x74) from [<c0127af4>] (do_vfs_ioctl+0x2a8/0x2e4)
[ 12.476074] [<c0127af4>] (do_vfs_ioctl+0x2a8/0x2e4) from [<c0127b90>] (sys_ioctl+0x60/0x84)
[ 12.476104] [<c0127b90>] (sys_ioctl+0x60/0x84) from [<c00130e0>] (ret_fast_syscall+0x0/0x3c)
[ 12.476135] Code: e594303c e3530010 15943024 13a02000 (15832008)
[ 12.476135] ---[ end trace da227214a82491b9 ]---
[ 12.476745] ipc_destroy failed: status [0xfffffff9]
[ 12.782196] (stk) :line disc installation timed out
[ 12.782196] (stk) :ldisc_install = 0
[ 13.637817] init: upstart-udev-bridge main process (1081) killed by ABRT signal
[ 13.658386] init: upstart-udev-bridge main process ended, respawning
[ 13.725555] init: upstart-udev-bridge main process (1200) killed by ABRT signal
[ 13.741668] init: upstart-udev-bridge main process ended, respawning
[ 13.955444] (stk) :ldisc_install = 1
[ 13.971008] init: upstart-udev-bridge main process (1220) killed by ABRT signal
[ 13.992034] init: upstart-udev-bridge main process ended, respawning
[ 14.081115] omap_vout omap_vout: Unable to apply changes

[Chris Bainbridge (chris-bainbridge)]

This also happens with latest 3.1.1-29-linaro-lt-omap from TI PPA.

[Milosz Wasilewski (mwasilew)]

doesn't happen in 13.05 release.

[Chase Qi (chase-qi)]

Migrated to bugzilla: https://bugs.linaro.org/show_bug.cgi?id=579