Received: by 2002:a05:6602:2086:0:0:0:0 with SMTP id a6csp4427923ioa; Wed, 27 Apr 2022 03:45:54 -0700 (PDT) X-Google-Smtp-Source: ABdhPJy6VuXPxttLS7dbn90YPht1dBEeSf2kkYnoKdYVRlIdSJPqcG2zlyYHTXa4+7IysmqLIqc6 X-Received: by 2002:a05:6a00:1a4a:b0:4f7:be32:3184 with SMTP id h10-20020a056a001a4a00b004f7be323184mr29349591pfv.65.1651056354526; Wed, 27 Apr 2022 03:45:54 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1651056354; cv=none; d=google.com; s=arc-20160816; b=0oVjoxbudKDJ7ox+XF8Njfp4QDWMrDyJz9S3KlNmSKLMIDtAgDHPGL6OP1UmS6Vcvn oiVjhPzZftrltJe0EScSJQ/oNRn8AwIqjyOUTgToci/ZP8Pey6bEWlC2f0Euy/K9GiIb imbdS6R/UppvWOP/msdZFMo1zMtDTstDgIGdY0oc0glnaBgIQuac2ryFLTHFWfsIfDgu A12Yiys+B/cDgsSt9NEvySKr3YUZpOjDH4QHu01bXf6b6yh6rwxUEZEQgCWvOZAuyiVm l5ym3P1+JclPo1cqatXLkJqjFoZHOIILYLQX/R6sR8SbFcW1iMlOPwqSSvWRa/KcjFQH 7i2Q== ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=arc-20160816; h=list-id:precedence:content-transfer-encoding:mime-version :references:in-reply-to:message-id:subject:cc:to:from:date; bh=VtkEF4dPMZsRokVmpogiZzgAMQHp17aK6+TduIJjqvk=; b=Yq21hDruITF977TsVAYwIwlz6OWt9nXb7F8vRilevvo9Wh/iw5FE2NBA0BTlndV0lT BKl8poH+HQyEwv7x7RmaQrDNKBb4amZuhWXZqq/rPOFJRTyZZSMdwGws+9H+8NfJ8HNd Pcvm2RoHEK2O948dEKYrm2FB5b0FqGO/UHYiDsmqa9E0tSCWCOR2Ia4c0YQt/ZMKSu3K AC8IK4oWVGU11tedxN5HmRV9P55Ga//IPLAmBrgRJXitzu1PkYwesEj+vCFyMJwZJI4a YrZZTs/CN53b2NDAJDvFeUX7ni9Sdo7UNI6KuC/Q0uMVDXPT//71CRPLOW+8gzSHxsPE 4ZBQ== ARC-Authentication-Results: i=1; mx.google.com; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 2620:137:e000::1:18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org Return-Path: Received: from lindbergh.monkeyblade.net (lindbergh.monkeyblade.net. [2620:137:e000::1:18]) by mx.google.com with ESMTPS id e63-20020a636942000000b003a34285e531si1055324pgc.528.2022.04.27.03.45.54 (version=TLS1_3 cipher=TLS_AES_256_GCM_SHA384 bits=256/256); Wed, 27 Apr 2022 03:45:54 -0700 (PDT) Received-SPF: pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 2620:137:e000::1:18 as permitted sender) client-ip=2620:137:e000::1:18; Authentication-Results: mx.google.com; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 2620:137:e000::1:18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org Received: from vger.kernel.org (vger.kernel.org [23.128.96.18]) by lindbergh.monkeyblade.net (Postfix) with ESMTP id 419284131A2; Wed, 27 Apr 2022 02:55:33 -0700 (PDT) Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1351917AbiDZP0E (ORCPT + 99 others); Tue, 26 Apr 2022 11:26:04 -0400 Received: from lindbergh.monkeyblade.net ([23.128.96.19]:55380 "EHLO lindbergh.monkeyblade.net" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S245239AbiDZP0D (ORCPT ); Tue, 26 Apr 2022 11:26:03 -0400 Received: from ams.source.kernel.org (ams.source.kernel.org [145.40.68.75]) by lindbergh.monkeyblade.net (Postfix) with ESMTPS id A67C19FE6; Tue, 26 Apr 2022 08:22:55 -0700 (PDT) Received: from smtp.kernel.org (relay.kernel.org [52.25.139.140]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) by ams.source.kernel.org (Postfix) with ESMTPS id 523A5B820D7; Tue, 26 Apr 2022 15:22:54 +0000 (UTC) Received: by smtp.kernel.org (Postfix) with ESMTPSA id 0ECCCC385A0; Tue, 26 Apr 2022 15:22:51 +0000 (UTC) Date: Tue, 26 Apr 2022 11:22:50 -0400 From: Steven Rostedt To: Patrick Wang Cc: paulmck@kernel.org, frederic@kernel.org, quic_neeraju@quicinc.com, josh@joshtriplett.org, mathieu.desnoyers@efficios.com, joel@joelfernandes.org, jiangshanlai@gmail.com, rcu@vger.kernel.org, linux-kernel@vger.kernel.org Subject: Re: [PATCH v3] rcu: ftrace: avoid tracing a few functions executed in stop machine Message-ID: <20220426112250.6f56e695@gandalf.local.home> In-Reply-To: <20220426105511.53010-1-patrick.wang.shcn@gmail.com> References: <20220426105511.53010-1-patrick.wang.shcn@gmail.com> X-Mailer: Claws Mail 3.17.8 (GTK+ 2.24.33; x86_64-pc-linux-gnu) MIME-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit X-Spam-Status: No, score=-1.9 required=5.0 tests=BAYES_00, HEADER_FROM_DIFFERENT_DOMAINS,MAILING_LIST_MULTI,RDNS_NONE, SPF_HELO_NONE autolearn=no autolearn_force=no version=3.4.6 X-Spam-Checker-Version: SpamAssassin 3.4.6 (2021-04-09) on lindbergh.monkeyblade.net Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On Tue, 26 Apr 2022 18:55:11 +0800 Patrick Wang wrote: > Because of the change of stop machine implementation, there are functions > being called while waiting currently: > > ---------------------------------------------------------------- > Former stop machine wait loop: > do { > cpu_relax(); => macro > ... > } while (curstate != STOPMACHINE_EXIT); > ----------------------------------------------------------------- > Current stop machine wait loop: > do { > stop_machine_yield(cpumask); => function (notraced) > ... > touch_nmi_watchdog(); => function (notraced, inside calls also notraced) > ... > rcu_momentary_dyntick_idle(); => function (notraced, inside calls traced) > } while (curstate != MULTI_STOP_EXIT); > ------------------------------------------------------------------ > > These functions (including the calls inside) should be marked notrace to avoid > their codes being updated when they are being called. The calls inside > rcu_momentary_dyntick_idle() still remain traced, and will cause crash: > > rcu: INFO: rcu_preempt detected stalls on CPUs/tasks: > rcu: 1-...!: (0 ticks this GP) idle=14f/1/0x4000000000000000 softirq=3397/3397 fqs=0 > rcu: 3-...!: (0 ticks this GP) idle=ee9/1/0x4000000000000000 softirq=5168/5168 fqs=0 > (detected by 0, t=8137 jiffies, g=5889, q=2 ncpus=4) > Task dump for CPU 1: > task:migration/1 state:R running task stack: 0 pid: 19 ppid: 2 flags:0x00000000 > Stopper: multi_cpu_stop+0x0/0x18c <- stop_machine_cpuslocked+0x128/0x174 > Call Trace: > Task dump for CPU 3: > task:migration/3 state:R running task stack: 0 pid: 29 ppid: 2 flags:0x00000000 > Stopper: multi_cpu_stop+0x0/0x18c <- stop_machine_cpuslocked+0x128/0x174 > Call Trace: > rcu: rcu_preempt kthread timer wakeup didn't happen for 8136 jiffies! g5889 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 > rcu: Possible timer handling issue on cpu=2 timer-softirq=594 > rcu: rcu_preempt kthread starved for 8137 jiffies! g5889 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=2 > rcu: Unless rcu_preempt kthread gets sufficient CPU time, OOM is now expected behavior. > rcu: RCU grace-period kthread stack dump: > task:rcu_preempt state:I stack: 0 pid: 14 ppid: 2 flags:0x00000000 > Call Trace: > schedule+0x56/0xc2 > schedule_timeout+0x82/0x184 > rcu_gp_fqs_loop+0x19a/0x318 > rcu_gp_kthread+0x11a/0x140 > kthread+0xee/0x118 > ret_from_exception+0x0/0x14 > rcu: Stack dump where RCU GP kthread last ran: > Task dump for CPU 2: > task:migration/2 state:R running task stack: 0 pid: 24 ppid: 2 flags:0x00000000 > Stopper: multi_cpu_stop+0x0/0x18c <- stop_machine_cpuslocked+0x128/0x174 > Call Trace: > > Mark the calls inside rcu_momentary_dyntick_idle(): > rcu_preempt_deferred_qs() > rcu_preempt_need_deferred_qs() > rcu_preempt_deferred_qs_irqrestore() > as notrace to prevent this. > > Signed-off-by: Patrick Wang > --- > v1->v2: > - Modify log message. Acked-by: Steven Rostedt (Google) -- Steve