Received: by 10.192.165.148 with SMTP id m20csp110400imm; Tue, 1 May 2018 18:45:44 -0700 (PDT) X-Google-Smtp-Source: AB8JxZpR9W5r3fCXlGcOV3llQKB5dpEdsa5Kh6VJZv3zO7jmZYv2nZhFqDuEhfL8eVAncFJRfPVe X-Received: by 2002:a17:902:683:: with SMTP id 3-v6mr18253123plh.206.1525225544937; Tue, 01 May 2018 18:45:44 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1525225544; cv=none; d=google.com; s=arc-20160816; b=t6tAgsdPVy6X6+NqbjXvU+sng+o9YRdu0r9O7BuRbM1dFiV56A5saPA2u+5134poLY nBPdIoFxIchsAoH6XyWaTkGciFSvxMI1d+sMfqB/QixvZV0aWqkF+xBPMNDBynPZFGNc 1NtGzDzQRFKakxXRNu/ekcCbxMNDTRjxinxfbag/f79BpOzWjkidHf3WCOuWIVSLakvy 1cdzE8DpdSxddcg+uQTAivT/EZgAsTMDoK7HqPmeBqfPnhtI2y/ZSoXXOAi4eJejK/ge eCmEAbTqtyrr0o3AX8p6fE3k7J0bCDk4R3yH0kU/zLD/5l4OdmA0lJQA+qZXGlqhuuo0 +pQA== ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=arc-20160816; h=list-id:precedence:sender:message-id:date:subject:cc:to:from :dkim-signature:arc-authentication-results; bh=2sO3hbGpQ7mQfCj/WRq1JZdI+DMM7rk2nUvsy/pNOmE=; b=C3VMwxF1TeolyPdXHxx2fdhp0Izq7sCuh/aIgRPapILbgCLvlYZtWEW/J+POiLEwze /CWZtu1CWG0Xiilu3mf+pFmd1wecFgwFm7HYsH6wvBqaRehtcCcCOL0kbl4xgCAU7sM3 h2fVDetxWLVAFy3PGGU1ghr7pahIYyohb/2sTQjgGokrmrVSY36JHOwGlvkblna2i4hW bzPeGTxmYsl8NgPptN4ywf+pmxuId6Ct86ixw0BFDCwd4pBgbxeSSag4iR0ir0azBKit uHu0n7x4DGfpxpdcDB2efhTJgu4LSzmXagMeZZNBegG9nVlK3klWkUgUldvPCft8A04l yYzw== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@google.com header.s=20161025 header.b=e5+UY0zX; spf=pass (google.com: best guess record for domain of linux-kernel-owner@vger.kernel.org designates 209.132.180.67 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=REJECT sp=REJECT dis=NONE) header.from=google.com Return-Path: Received: from vger.kernel.org (vger.kernel.org. [209.132.180.67]) by mx.google.com with ESMTP id m39-v6si10663330plg.570.2018.05.01.18.45.31; Tue, 01 May 2018 18:45:44 -0700 (PDT) Received-SPF: pass (google.com: best guess record for domain of linux-kernel-owner@vger.kernel.org designates 209.132.180.67 as permitted sender) client-ip=209.132.180.67; Authentication-Results: mx.google.com; dkim=pass header.i=@google.com header.s=20161025 header.b=e5+UY0zX; spf=pass (google.com: best guess record for domain of linux-kernel-owner@vger.kernel.org designates 209.132.180.67 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=REJECT sp=REJECT dis=NONE) header.from=google.com Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1751262AbeEBBpL (ORCPT + 99 others); Tue, 1 May 2018 21:45:11 -0400 Received: from mail-pf0-f193.google.com ([209.85.192.193]:40873 "EHLO mail-pf0-f193.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751181AbeEBBpH (ORCPT ); Tue, 1 May 2018 21:45:07 -0400 Received: by mail-pf0-f193.google.com with SMTP id f189so10372602pfa.7 for ; Tue, 01 May 2018 18:45:07 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=20161025; h=from:to:cc:subject:date:message-id; bh=2sO3hbGpQ7mQfCj/WRq1JZdI+DMM7rk2nUvsy/pNOmE=; b=e5+UY0zXCGQe+kA6Ye6YHQHTNEm4sv6y/O8pb7m7Wz2ehKy7bsyZiKvcfVoBqUS2al T+ZUxBf7r8GJlxqHdCLDasX8sfDNefXFsQ0KdJDDI2VdY16WB1tjnYJiWwmx186ij4o+ ivqbH9AxeZHLjqkCnp8OXrA+mGpJsxYGjXZRWZy8KBNEF2A/PuAPBXlv7I45ULS61N72 ylIVOVrnRJm/89fyCjmmptXZl+WMUo6D9+uXJcC2fTlcTohGkTd3zxUW6SJvNLgbGGq7 ZplHFYPSO74qMc5KkG2EsI4W7ewWm4mQHQtcGqHLXvWmgPMD8NXFHoKfy24vFB8PUIwL 0QWA== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:from:to:cc:subject:date:message-id; bh=2sO3hbGpQ7mQfCj/WRq1JZdI+DMM7rk2nUvsy/pNOmE=; b=lkwuev81CCHATLmSessf3sLfe0JWnNCbyO+vHbtEa47O9ddBZGqDPsb7RNdTQ+wGOX YZKYrbaL6DokMh1uFNdI1SlIqm56FwEAzhbdXp/+L3KFk8hjp6R6Dr3Ld2D07cOc+0qu d6OIvV026vitHbaDl9eSri1HGRYMoNKJd/Q/gDK4TM92kg7pjiZd9YhYVLBJvtVoJ7Dz ZV8yfZvqCr3Xo3YEAn/VsJUUcEpG0qV3GK0hGSUu+Nr0o1cQtr/mON9Q47N/IpvkXKaA IPBA23vOJiLFQOkg4oRyq1LelkgDNSjUVtEFvo5z2Sn/i7D8kTKKTMivuot23VL4WzJc /A6Q== X-Gm-Message-State: ALQs6tAQrZC56RLBl3LqCsUnpreASrnBWDTLH0MV8aUOlsRQM0HLYuuF 8HRwZiifPkHLSDCzc/Xn3uuiT3s+6B8= X-Received: by 2002:a63:3f06:: with SMTP id m6-v6mr14932450pga.340.1525225506328; Tue, 01 May 2018 18:45:06 -0700 (PDT) Received: from joelaf.mtv.corp.google.com ([2620:0:1000:1600:3122:ea9c:d178:eb]) by smtp.gmail.com with ESMTPSA id g26sm20876163pfh.76.2018.05.01.18.45.04 (version=TLS1_2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128); Tue, 01 May 2018 18:45:05 -0700 (PDT) From: Joel Fernandes To: linux-kernel@vger.kernel.org Cc: Joel Fernandes , Steven Rostedt , Peter Zilstra , Ingo Molnar , Mathieu Desnoyers , Tom Zanussi , Namhyung Kim , Thomas Glexiner , Boqun Feng , Paul McKenney , Frederic Weisbecker , Randy Dunlap , Masami Hiramatsu , Fenguang Wu , Baohong Liu , Vedang Patel , kernel-team@android.com, stable@vger.kernel.org Subject: [PATCH] softirq: reorder trace_softirqs_on to prevent lockdep splat Date: Tue, 1 May 2018 18:44:39 -0700 Message-Id: <20180502014439.87828-1-joelaf@google.com> X-Mailer: git-send-email 2.17.0.441.gb46fe60e1d-goog Sender: linux-kernel-owner@vger.kernel.org Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org I'm able to reproduce a lockdep splat with config options: CONFIG_PROVE_LOCKING=y, CONFIG_DEBUG_LOCK_ALLOC=y and CONFIG_PREEMPTIRQ_EVENTS=y $ echo 1 > /d/tracing/events/preemptirq/preempt_enable/enable --- [ 26.112609] DEBUG_LOCKS_WARN_ON(current->softirqs_enabled) [ 26.112636] WARNING: CPU: 0 PID: 118 at kernel/locking/lockdep.c:3854 [...] [ 26.144229] Call Trace: [ 26.144926] [ 26.145506] lock_acquire+0x55/0x1b0 [ 26.146499] ? __do_softirq+0x46f/0x4d9 [ 26.147571] ? __do_softirq+0x46f/0x4d9 [ 26.148646] trace_preempt_on+0x8f/0x240 [ 26.149744] ? trace_preempt_on+0x4d/0x240 [ 26.150862] ? __do_softirq+0x46f/0x4d9 [ 26.151930] preempt_count_sub+0x18a/0x1a0 [ 26.152985] __do_softirq+0x46f/0x4d9 [ 26.153937] irq_exit+0x68/0xe0 [ 26.154755] smp_apic_timer_interrupt+0x271/0x280 [ 26.156056] apic_timer_interrupt+0xf/0x20 [ 26.157105] The issue was this: preempt_count = 1 << SOFTIRQ_SHIFT __local_bh_enable(cnt = 1 << SOFTIRQ_SHIFT) { if (softirq_count() == (cnt && SOFTIRQ_MASK)) { trace_softirqs_on() { current->softirqs_enabled = 1; } } preempt_count_sub(cnt) { trace_preempt_on() { tracepoint() { rcu_read_lock_sched() { // jumps into lockdep Where preempt_count still has softirqs disabled, but current->softirqs_enabled is true, and we get a splat. Cc: Steven Rostedt Cc: Peter Zilstra Cc: Ingo Molnar Cc: Mathieu Desnoyers Cc: Tom Zanussi Cc: Namhyung Kim Cc: Thomas Glexiner Cc: Boqun Feng Cc: Paul McKenney Cc: Frederic Weisbecker Cc: Randy Dunlap Cc: Masami Hiramatsu Cc: Fenguang Wu Cc: Baohong Liu Cc: Vedang Patel Cc: kernel-team@android.com Cc: stable@vger.kernel.org Reviewed-by: Steven Rostedt (VMware) Fixes: d59158162e032 ("tracing: Add support for preempt and irq enable/disable events") Signed-off-by: Joel Fernandes --- kernel/softirq.c | 6 +++++- 1 file changed, 5 insertions(+), 1 deletion(-) diff --git a/kernel/softirq.c b/kernel/softirq.c index 24d243ef8e71..47e2f61938c0 100644 --- a/kernel/softirq.c +++ b/kernel/softirq.c @@ -139,9 +139,13 @@ static void __local_bh_enable(unsigned int cnt) { lockdep_assert_irqs_disabled(); + if (preempt_count() == cnt) + trace_preempt_on(CALLER_ADDR0, get_lock_parent_ip()); + if (softirq_count() == (cnt & SOFTIRQ_MASK)) trace_softirqs_on(_RET_IP_); - preempt_count_sub(cnt); + + __preempt_count_sub(cnt); } /* -- 2.17.0.441.gb46fe60e1d-goog