From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: X-Spam-Checker-Version: SpamAssassin 3.4.0 (2014-02-07) on dcvr.yhbt.net X-Spam-Level: X-Spam-ASN: AS31976 209.132.180.0/23 X-Spam-Status: No, score=-3.0 required=3.0 tests=AWL,BAYES_00,DKIM_SIGNED, DKIM_VALID,DKIM_VALID_AU,FREEMAIL_FORGED_FROMDOMAIN,FREEMAIL_FROM, HEADER_FROM_DIFFERENT_DOMAINS,RCVD_IN_DNSWL_HI,T_RP_MATCHES_RCVD shortcircuit=no autolearn=ham autolearn_force=no version=3.4.0 Received: from vger.kernel.org (vger.kernel.org [209.132.180.67]) by dcvr.yhbt.net (Postfix) with ESMTP id F183820401 for ; Wed, 21 Jun 2017 21:40:31 +0000 (UTC) Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1752002AbdFUVka (ORCPT ); Wed, 21 Jun 2017 17:40:30 -0400 Received: from mail-wr0-f194.google.com ([209.85.128.194]:34947 "EHLO mail-wr0-f194.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751985AbdFUVk2 (ORCPT ); Wed, 21 Jun 2017 17:40:28 -0400 Received: by mail-wr0-f194.google.com with SMTP id z45so30132699wrb.2 for ; Wed, 21 Jun 2017 14:40:28 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20161025; h=from:to:cc:subject:references:user-agent:in-reply-to:date :message-id:mime-version:content-transfer-encoding; bh=gV2Qs2zTkmzeQVHotrN2FXOxGQbS4kZec+oITIl35hg=; b=CCkpj6Rz2IuyqaMb095bQVbunCs8vSaLF67X74ZYeZV0uIZKcdgiicwu1hHHEBLZec Z2bZW+k6Fk5FRuPLtwLvsJKhiTdcodjqLVYzVx2hYfqPxXosCbWI1dHvLgcY3rpUDrmK UvVsWL5fMVN8qdH3IOuzfFDMbgj1VAvSKYh8+nvQbpe8Bcx/YknnFVjZElCYOuYXryNk KixnURkHBeC9OXpkEIDZvizEvyCBrnt2dqWRYdPNI4TMue4+/nMdsXC0skm13RDqF6jR OhYbFw5zbu6rhzn4e89p2Hk6YAXMk897+lrxLVvilVGZu6WfTu8Gte8aJ+AYlka8gv6X 8POQ== 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:references:user-agent :in-reply-to:date:message-id:mime-version:content-transfer-encoding; bh=gV2Qs2zTkmzeQVHotrN2FXOxGQbS4kZec+oITIl35hg=; b=UiS2NJTuWuRZn/p+a+Vwt7gNNPHmlEvCJYCfmFMqgWI71oYOqw0z2ce99P/tDCfG6C ZjodEUXImX0NTF7uJYWyMLY5ZooHCX7OT6tswT13fAqP1ksIumWAVlPnLufyDADTYcmd gGQhyWqaFvp2cuZAa8fCIilxHyddgqCsH9NQNf5w9uGuOK2AbYn0uWICjjEp6VZnvJpt orqXYYv1Mghrgzo19EyVY9eY2SqFO8RAJ5LAj8XqHPIVz9Zz0G5jziUA091V07TpLyVK W+mDPuUsIUd8Ne0Obm0cH7Yo/CQtho/BAFTIUApBXAYEch1vS5xq4XoOyhrlWS93WML3 /TKQ== X-Gm-Message-State: AKS2vOxK/w2yI4wAOKot8ELqUYqr2S8y9ooZj0CNw1w2zVpSU9OIpK/O Rz448eI3MjdPQg== X-Received: by 10.80.135.183 with SMTP id a52mr27097482eda.90.1498081227153; Wed, 21 Jun 2017 14:40:27 -0700 (PDT) Received: from snth ([92.109.130.42]) by smtp.gmail.com with ESMTPSA id 4sm9528404eds.48.2017.06.21.14.40.25 (version=TLS1_2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128); Wed, 21 Jun 2017 14:40:26 -0700 (PDT) Received: from avar by snth with local (Exim 4.84_2) (envelope-from ) id 1dNnMP-0000Bg-Hw; Wed, 21 Jun 2017 23:40:25 +0200 From: =?utf-8?B?w4Z2YXIgQXJuZmrDtnLDsA==?= Bjarmason To: Morten Welinder Cc: GIT Mailing List , Junio C Hamano , Jeff King , Stefan Beller , Simon Ruderich Subject: Re: [PATCH v2] die(): stop hiding errors due to overzealous recursion guard References: <87podz8v6v.fsf@gmail.com> <20170621204742.15722-1-avarab@gmail.com> User-agent: Debian GNU/Linux 8.8 (jessie); Emacs 25.1.1; mu4e 0.9.19 In-reply-to: Date: Wed, 21 Jun 2017 23:40:25 +0200 Message-ID: <87bmph81fa.fsf@gmail.com> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 8bit Sender: git-owner@vger.kernel.org Precedence: bulk List-ID: X-Mailing-List: git@vger.kernel.org On Wed, Jun 21 2017, Morten Welinder jotted: > If threading is the issue, how do you get meaningful results from > reading and updating > "dying" with no use of atomic types or locks? Other than winning the > implied race, of > course. Threading isn't the issue. The issue is that we have an overzelous recursion guard that will demonstrably cause us to lose errors in the face of threading. By amending the guard so that we won't run into it in practice so soon that we'll hide errors (see the empirical results in the commit message) we solve *that* issue in practice. The current code & the code I'm adding here suffers from race conditions & non-atomic updates, but for the reasons explained at the bottom of the the commit message that's OK. We're not relying on being able to do x++ and have x be 1, 2, 3 etc. in the face of threading, we're just currently relying on it being larger than 1, or with my patch eventually larger than 1024. It is possible with my patch that we'll never take the "dying == 2" -> "warning(..)" branch (and empirical results show that happens), but it's enough for the purposes of the default die handler (which we really should be overriding if we're doing threading, but sometimes we're lazy) that it works most of the time, and that we at least don't hide real errors, which is the issue with it right now. > On Wed, Jun 21, 2017 at 4:47 PM, Ævar Arnfjörð Bjarmason > wrote: >> Change the recursion limit for the default die routine from a *very* >> low 1 to 1024. This ensures that infinite recursions are broken, but >> doesn't lose the meaningful error messages under threaded execution >> where threads concurrently start to die. >> >> The intent of the existing code, as explained in commit >> cd163d4b4e ("usage.c: detect recursion in die routines and bail out >> immediately", 2012-11-14), is to break infinite recursion in cases >> where the die routine itself calls die(), and would thus infinitely >> recurse. >> >> However, doing that very aggressively by immediately printing out >> "recursion detected in die handler" if we've already called die() once >> means that threaded invocations of git can end up only printing out >> the "recursion detected" error, while hiding the meaningful error. >> >> An example of this is running a threaded grep which dies on execution >> against pretty much any repo, git.git will do: >> >> git grep -P --threads=8 '(*LIMIT_MATCH=1)-?-?-?---$' >> >> With the current version of git this will print some combination of >> multiple PCRE failures that caused the abort and multiple "recursion >> detected", some invocations will print out multiple "recursion >> detected" errors with no PCRE error at all! >> >> Before this change, running the above grep command 1000 times against >> git.git[1] and taking the top 20 results will on my system yield the >> following distribution of actual errors ("E") and recursion >> errors ("R"): >> >> 322 E R >> 306 E >> 116 E R R >> 65 R R >> 54 R E >> 49 E E >> 44 R >> 15 E R R R >> 9 R R R >> 7 R E R >> 5 R R E >> 3 E R R R R >> 2 E E R >> 1 R R R R >> 1 R R R E >> 1 R E R R >> >> The exact results are obviously random and system-dependent, but this >> shows the race condition in this code. Some small part of the time >> we're about to print out the actual error ("E") but another thread's >> recursion error beats us to it, and sometimes we print out nothing but >> the recursion error. >> >> With this change we get, now with "W" to mean the new warning being >> emitted indicating that we've called die() many times: >> >> 502 E >> 160 E W E >> 120 E E >> 53 E W >> 35 E W E E >> 34 W E E >> 29 W E E E >> 16 E E W >> 16 E E E >> 11 W E E E E >> 7 E E W E >> 4 W E >> 3 W W E E >> 2 E W E E E >> 1 W W E >> 1 W E W E >> 1 E W W E E E >> 1 E W W E E >> 1 E W W E >> 1 E W E E W >> >> Which still sucks a bit, due to a still present race-condition in this >> code we're sometimes going to print out several errors still, or >> several warnings, or two duplicate errors without the warning. >> >> But we will never have a case where we completely hide the actual >> error as we do now. >> >> Now, git-grep could make use of the pluggable error facility added in >> commit c19a490e37 ("usage: allow pluggable die-recursion checks", >> 2013-04-16). There's other threaded code that calls set_die_routine() >> or set_die_is_recursing_routine(). >> >> But this is about fixing the general die() behavior with threading >> when we don't have such a custom routine yet. Right now the common >> case is not an infinite recursion in the handler, but us losing error >> messages by default because we're overly paranoid about our recursion >> check. >> >> So let's just set the recursion limit to a number higher than the >> number of threads we're ever likely to spawn. Now we won't lose >> errors, and if we have a recursing die handler we'll still die within >> microseconds. >> >> There are race conditions in this code itself, in particular the >> "dying" variable is not thread mutexed, so we e.g. won't be dying at >> exactly 1024, or for that matter even be able to accurately test >> "dying == 2", see the cases where we print out more than one "W" >> above. >> >> But that doesn't really matter, for the recursion guard we just need >> to die "soon", not at exactly 1024 calls, and for printing the correct >> error and only one warning most of the time in the face of threaded >> death this is good enough and a net improvement on the current code. >> >> 1. for i in {1..1000}; do git grep -P --threads=8 '(*LIMIT_MATCH=1)-?-?-?---$' 2>&1|perl -pe 's/^fatal: r.*/R/; s/^fatal: p.*/E/; s/^warning.*/W/' | tr '\n' ' '; echo; done | sort | uniq -c | sort -nr | head -n 20 >> >> Signed-off-by: Ævar Arnfjörð Bjarmason >> --- >> >> This replaces v1 and takes into account the feedback in this thread >> (thanks everyone!). >> >> The commit message is also much improved and includes more rationale >> originally in my reply to Stefan in 87podz8v6v.fsf@gmail.com >> >> usage.c | 18 +++++++++++++++++- >> 1 file changed, 17 insertions(+), 1 deletion(-) >> >> diff --git a/usage.c b/usage.c >> index 2f87ca69a8..1ea7df9a20 100644 >> --- a/usage.c >> +++ b/usage.c >> @@ -44,7 +44,23 @@ static void warn_builtin(const char *warn, va_list params) >> static int die_is_recursing_builtin(void) >> { >> static int dying; >> - return dying++; >> + /* >> + * Just an arbitrary number X where "a < x < b" where "a" is >> + * "maximum number of pthreads we'll ever plausibly spawn" and >> + * "b" is "something less than Inf", since the point is to >> + * prevent infinite recursion. >> + */ >> + static const int recursion_limit = 1024; >> + >> + dying++; >> + if (dying > recursion_limit) { >> + return 1; >> + } else if (dying == 2) { >> + warning("die() called many times. Recursion error or racy threaded death!"); >> + return 0; >> + } else { >> + return 0; >> + } >> } >> >> /* If we are in a dlopen()ed .so write to a global variable would segfault >> -- >> 2.13.1.611.g7e3b11ae1 >>