Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1755632Ab3I3RZD (ORCPT ); Mon, 30 Sep 2013 13:25:03 -0400 Received: from mx1.redhat.com ([209.132.183.28]:36475 "EHLO mx1.redhat.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1755340Ab3I3RZB (ORCPT ); Mon, 30 Sep 2013 13:25:01 -0400 Date: Mon, 30 Sep 2013 13:24:47 -0400 From: Dave Jones To: Stephen Smalley , Linux Kernel , eparis@redhat.com, james.l.morris@oracle.com Subject: Re: [PATCH] conditionally reschedule while loading selinux policy. Message-ID: <20130930172447.GA10562@redhat.com> Mail-Followup-To: Dave Jones , Stephen Smalley , Linux Kernel , eparis@redhat.com, james.l.morris@oracle.com References: <20130916173036.GA17950@redhat.com> <52374350.1090403@tycho.nsa.gov> <20130916184030.GA15800@redhat.com> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <20130916184030.GA15800@redhat.com> User-Agent: Mutt/1.5.21 (2010-09-15) Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 2817 Lines: 60 On Mon, Sep 16, 2013 at 02:40:30PM -0400, Dave Jones wrote: > On a slow machine (with debugging enabled), upgrading selinux policy may take > a considerable amount of time. Long enough that the softlockup detector > gets triggered. > > The backtrace looks like this.. > > > BUG: soft lockup - CPU#2 stuck for 23s! [load_policy:19045] > > Call Trace: > > [] symcmp+0xf/0x20 > > [] hashtab_search+0x47/0x80 > > [] mls_convert_context+0xdc/0x1c0 > > [] convert_context+0x378/0x460 > > [] ? security_context_to_sid_core+0x240/0x240 > > [] sidtab_map+0x45/0x80 > > [] security_load_policy+0x3ff/0x580 With that patch applied, the problem seems to have moved elsewhere.. BUG: soft lockup - CPU#3 stuck for 22s! [load_policy:8119] irq event stamp: 1590886 hardirqs last enabled at (1590885): [] __slab_alloc.constprop.78+0x4c0/0x4d7 hardirqs last disabled at (1590886): [] apic_timer_interrupt+0x6a/0x80 softirqs last enabled at (1590336): [] __do_softirq+0x169/0x200 softirqs last disabled at (1590331): [] irq_exit+0x11d/0x140 RIP: 0010:[] [] hashtab_insert+0x62/0x110 Call Trace: [] policydb_read+0xc25/0x1200 [] ? is_module_text_address+0x19/0x40 [] security_load_policy+0x10e/0x580 [] ? sched_clock_cpu+0xa8/0x100 [] ? sched_clock_local+0x1d/0x80 [] ? sched_clock_cpu+0xa8/0x100 [] ? sched_clock_local+0x1d/0x80 [] ? sched_clock_cpu+0xa8/0x100 [] ? __change_page_attr_set_clr+0x82a/0xa50 [] ? sched_clock_cpu+0xa8/0x100 [] ? retint_restore_args+0xe/0xe [] ? trace_hardirqs_on_caller+0xfd/0x1c0 [] ? trace_hardirqs_on_thunk+0x3a/0x3f [] ? rcu_irq_exit+0x68/0xb0 [] ? retint_restore_args+0xe/0xe [] sel_write_load+0xa7/0x770 [] ? vfs_write+0x1c3/0x200 [] ? security_file_permission+0x1e/0xa0 [] vfs_write+0xbb/0x200 [] ? fget_light+0x397/0x4b0 [] SyS_write+0x47/0xa0 [] tracesys+0xdd/0xe2 We're holding a bunch of locks here, so we can't just cond_resched. Thoughts ? Dave -- 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/