From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (qmail 3059 invoked by alias); 6 Dec 2018 15:54:05 -0000 Mailing-List: contact gdb-patches-help@sourceware.org; run by ezmlm Precedence: bulk List-Id: List-Subscribe: List-Archive: List-Post: List-Help: , Sender: gdb-patches-owner@sourceware.org Received: (qmail 3043 invoked by uid 89); 6 Dec 2018 15:54:04 -0000 Authentication-Results: sourceware.org; auth=none X-Spam-SWARE-Status: No, score=-0.9 required=5.0 tests=BAYES_00,KAM_LAZY_DOMAIN_SECURITY,SPF_HELO_PASS autolearn=no version=3.3.2 spammy= X-HELO: mx1.redhat.com Received: from mx1.redhat.com (HELO mx1.redhat.com) (209.132.183.28) by sourceware.org (qpsmtpd/0.93/v0.84-503-g423c35a) with ESMTP; Thu, 06 Dec 2018 15:54:03 +0000 Received: from smtp.corp.redhat.com (int-mx03.intmail.prod.int.phx2.redhat.com [10.5.11.13]) (using TLSv1.2 with cipher AECDH-AES256-SHA (256/256 bits)) (No client certificate requested) by mx1.redhat.com (Postfix) with ESMTPS id 33C385AFF5; Thu, 6 Dec 2018 15:54:02 +0000 (UTC) Received: from [127.0.0.1] (ovpn04.gateway.prod.ext.ams2.redhat.com [10.39.146.4]) by smtp.corp.redhat.com (Postfix) with ESMTP id 71DF92CFBD; Thu, 6 Dec 2018 15:53:49 +0000 (UTC) Subject: Re: [PATCH] Implement timestamp'ed output on "make check" To: Sergio Durigan Junior , Pedro Franco de Carvalho References: <87va47ojas.fsf@linux.vnet.ibm.com> <87a7ljdaax.fsf@redhat.com> <87sgzbohzb.fsf@linux.vnet.ibm.com> <87efavbu02.fsf@redhat.com> <7d58b34f-3082-a266-7f38-79e4f80a533c@redhat.com> Cc: GDB Patches From: Pedro Alves Message-ID: <5ca80f12-c329-ac40-f6a9-eb8f2b71cceb@redhat.com> Date: Thu, 06 Dec 2018 15:54:00 -0000 User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:52.0) Gecko/20100101 Thunderbird/52.9.1 MIME-Version: 1.0 In-Reply-To: <7d58b34f-3082-a266-7f38-79e4f80a533c@redhat.com> Content-Type: multipart/mixed; boundary="------------E33F9F5047D09DB4BB8CEFA2" X-SW-Source: 2018-12/txt/msg00070.txt.bz2 This is a multi-part message in MIME format. --------------E33F9F5047D09DB4BB8CEFA2 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit Content-length: 1588 On 12/06/2018 03:29 PM, Pedro Alves wrote: > On 12/05/2018 08:26 PM, Sergio Durigan Junior wrote: > >> Unfortunately they haven't reached the commit which introduces the >> timestamp feature, so we still don't know exactly where they're hanging. > > Actually we could know even without the timestamp feature -- all the > testsuite/*/gdb.log files have something like: > > Test run by pedro on Tue Dec 4 18:15:17 2018 > > at the top, and ... > > runtest completed at Tue Dec 4 18:15:18 2018 > > ... at the bottom. > > So a simple script could extract that info and present it > in a convenient way. Here's a quick bash POC. Make sure to test in parallel mode (make check-parallel), so that each testcase gets its own gdb.log file and then run the script, like: $ cd gdb $ make -j8 check-parallel $ extract-times testsuite/outputs/ | sort -n | tail -n 10 33 seconds for testsuite/outputs/gdb.base/whatis-ptype-typedefs/gdb.log 35 seconds for testsuite/outputs/gdb.base/quit-live/gdb.log 35 seconds for testsuite/outputs/gdb.base/structs/gdb.log 37 seconds for testsuite/outputs/gdb.base/gdb-sigterm/gdb.log 39 seconds for testsuite/outputs/gdb.threads/attach-many-short-lived-threads/gdb.log 43 seconds for testsuite/outputs/gdb.base/multi-forks/gdb.log 48 seconds for testsuite/outputs/gdb.linespec/cpls-ops/gdb.log 64 seconds for testsuite/outputs/gdb.linespec/cpcompletion/gdb.log 78 seconds for testsuite/outputs/gdb.base/break-interp/gdb.log 149 seconds for testsuite/outputs/gdb.base/sigstep/gdb.log (that was run against an older test dir I had handy.) --------------E33F9F5047D09DB4BB8CEFA2 Content-Type: text/plain; charset=UTF-8; name="extract-times" Content-Transfer-Encoding: base64 Content-Disposition: attachment; filename="extract-times" Content-length: 533 IyEvYmluL2Jhc2gKCmZpbmQgJDEgLW5hbWUgImdkYi5sb2ciIHwgXAogICAg d2hpbGUgcmVhZCBsaW5lOwogICAgZG8KCSNlY2hvICRsaW5lCglzdGFydD0k KGhlYWQgLW4xICRsaW5lIHwgc2VkICdzL1Rlc3QgcnVuIGJ5IC4qIG9uIC8v ZycpCgllbmQ9JCh0YWlsIC1uMSAkbGluZSB8IHNlZCAncy8uKmNvbXBsZXRl ZCBhdCAvL2cnKQoJI2VjaG8gc3RhcnQ9JHN0YXJ0CgkjZWNobyBlbmQ9JGVu ZAoJc3RhcnRfc2VjPSQoZGF0ZSAtLWRhdGU9IiRzdGFydCIgJyslcycpCgll bmRfc2VjPSQoZGF0ZSAtLWRhdGU9IiRlbmQiICcrJXMnKQoJZHVyYXRpb25f c2VjPSQoKGVuZF9zZWMgLSBzdGFydF9zZWMpKQoJZWNobyAiJGR1cmF0aW9u X3NlYyBzZWNvbmRzIGZvciAkbGluZSIKICAgIGRvbmUK --------------E33F9F5047D09DB4BB8CEFA2--