From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1755092Ab1D0T71 (ORCPT ); Wed, 27 Apr 2011 15:59:27 -0400 Received: from sentry-two.sandia.gov ([132.175.109.14]:58010 "EHLO sentry-two.sandia.gov" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1753469Ab1D0T70 (ORCPT ); Wed, 27 Apr 2011 15:59:26 -0400 X-WSS-ID: 0LKBU6Z-0B-3GM-02 X-M-MSG: X-Server-Uuid: AF72F651-81B1-4134-BA8C-A8E1A4E620FF Message-ID: <4DB87585.3010607@sandia.gov> Date: Wed, 27 Apr 2011 13:59:01 -0600 From: "Jim Schutt" User-Agent: Thunderbird 2.0.0.24 (X11/20110128) MIME-Version: 1.0 To: paulmck@linux.vnet.ibm.com cc: linux-kernel@vger.kernel.org Subject: mcelog stalls on 2.6.39-rc5 X-Originating-IP: [134.253.95.179] X-PMX-Version: 5.6.0.2009776, Antispam-Engine: 2.7.2.376379, Antispam-Data: 2011.4.27.194815 X-PMX-Spam: Gauge=IIIIIIII, Probability=8%, Report=' BODY_SIZE_3000_3999 0, BODY_SIZE_5000_LESS 0, BODY_SIZE_7000_LESS 0, DATE_TZ_NA 0, WEBMAIL_SOURCE 0, WEBMAIL_XOIP 0, WEBMAIL_X_IP_HDR 0, __CT 0, __CTE 0, __CT_TEXT_PLAIN 0, __HAS_MSGID 0, __HAS_XOIP 0, __MIME_TEXT_ONLY 0, __MIME_VERSION 0, __MOZILLA_MSGID 0, __RATWARE_X_MAILER_CS_B 0, __SANE_MSGID 0, __TO_MALFORMED_2 0, __TO_NO_NAME 0, __URI_NO_PATH 0, __URI_NO_WWW 0, __URI_NS , __USER_AGENT 0' X-TMWD-Spam-Summary: TS=20110427195908; ID=1; SEV=2.3.1; DFV=B2011042719; IFV=NA; AIF=B2011042719; RPD=5.03.0010; ENG=NA; RPDID=7374723D303030312E30413031303230392E34444238373538442E303030323A534346535441543838363133332C73733D312C6667733D30; CAT=NONE; CON=NONE; SIG=AAAAAAAAAAAAAAAAAAAAAAAAfQ== X-MMS-Spam-Filter-ID: B2011042719_5.03.0010 X-WSS-ID: 61A6AA012TS2322136-01-01 Content-Type: text/plain; charset=utf-8; format=flowed Content-Transfer-Encoding: 7bit X-RSA-Inspected: yes X-RSA-Classifications: public X-RSA-Action: allow Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org Hi, Testing 2.6.39-rc5 is giving me the following stall: [ 5767.731001] INFO: rcu_sched_state detected stall on CPU 1 (t=60001 jiffies) [ 5767.732001] INFO: rcu_sched_state detected stalls on CPUs/tasks: { 1} (detected by 0, t=60002 jiffies) [ 5947.763001] INFO: rcu_sched_state detected stall on CPU 1 (t=240032 jiffies) [ 5947.764001] INFO: rcu_sched_state detected stalls on CPUs/tasks: { 1} (detected by 0, t=240034 jiffies) [ 6024.489362] libceph: mon0 172.17.40.34:6789 socket closed [ 6121.281139] INFO: task mcelog:6513 blocked for more than 120 seconds. [ 6121.287575] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 6121.295397] ffff880177aefce8 0000000000000082 ffffffff810339b6 ffff880226d35a40 [ 6121.302840] ffff88018226c3b0 ffff88018226c3b0 0000000000011e80 ffff880226d35a40 [ 6121.310284] ffff88018226c760 ffff880177aefe80 ffff880177aefd18 ffffffff813af047 [ 6121.317725] Call Trace: [ 6121.320176] [] ? calc_load_account_idle+0xe/0x1d [ 6121.326437] [] schedule+0x159/0x193 [ 6121.331569] [] schedule_timeout+0x36/0xe2 [ 6121.337223] [] ? trace_hardirqs_on+0x9/0x20 [ 6121.343047] [] do_wait_for_common+0x97/0xe3 [ 6121.348967] [] ? try_to_wake_up+0x200/0x200 [ 6121.354794] [] ? __raw_spin_lock_irq+0x17/0x2f [ 6121.360878] [] wait_for_common+0x36/0x4d [ 6121.366441] [] wait_for_completion+0x1d/0x1f [ 6121.372356] [] synchronize_sched+0x40/0x49 [ 6121.378096] [] ? find_get_pid+0x1b/0x1b [ 6121.383574] [] mce_read+0x17f/0x25d [ 6121.388707] [] ? rw_verify_area+0xac/0xdb [ 6121.394358] [] vfs_read+0xa9/0xe1 [ 6121.399317] [] sys_read+0x4c/0x70 [ 6121.404278] [] system_call_fastpath+0x16/0x1b [ 6127.795001] INFO: rcu_sched_state detected stall on CPU 1 (t=420064 jiffies) [ 6127.796001] INFO: rcu_sched_state detected stalls on CPUs/tasks: { 1} (detected by 0, t=420066 jiffies) [ 6241.410171] INFO: task mcelog:6513 blocked for more than 120 seconds. Reverting commit a4dd99250dc makes the stalls go away: rcu: create new rcu_access_index() and use in mce The MCE subsystem needs to sample an RCU-protected index outside of any protection for that index. If this was a pointer, we would use rcu_access_pointer(), but there is no corresponding rcu_access_index(). This commit therefore creates an rcu_access_index() and applies it to MCE. Signed-off-by: Paul E. McKenney Tested-by: Zdenek Kabelac Here's some bits from my config that might be relevant: # RCU Subsystem CONFIG_TREE_RCU=y # CONFIG_PREEMPT_RCU is not set # CONFIG_RCU_TRACE is not set CONFIG_RCU_FANOUT=64 # CONFIG_RCU_FANOUT_EXACT is not set # CONFIG_RCU_FAST_NO_HZ is not set # CONFIG_TREE_RCU_TRACE is not set CONFIG_PREEMPT_NOTIFIERS=y # CONFIG_PREEMPT_NONE is not set CONFIG_PREEMPT_VOLUNTARY=y # CONFIG_PREEMPT is not set CONFIG_X86_MCE=y CONFIG_X86_MCE_INTEL=y CONFIG_X86_MCE_AMD=y CONFIG_X86_MCE_THRESHOLD=y # CONFIG_X86_MCE_INJECT is not set CONFIG_EDAC_DECODE_MCE=y # CONFIG_EDAC_MCE_INJ is not set CONFIG_EDAC_MCE=y # CONFIG_SPARSE_RCU_POINTER is not set # CONFIG_RCU_TORTURE_TEST is not set CONFIG_RCU_CPU_STALL_DETECTOR=y CONFIG_RCU_CPU_STALL_TIMEOUT=60 CONFIG_RCU_CPU_STALL_DETECTOR_RUNNABLE=y Please let me know if there is anything I can do to help sort this out. -- Jim