Received: from malur.postgresql.org ([217.196.149.56]) by arkaria.postgresql.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_CBC_SHA1:256) (Exim 4.89) (envelope-from ) id 1gsgW6-0004IV-40 for pgsql-hackers@arkaria.postgresql.org; Sun, 10 Feb 2019 04:14:54 +0000 Received: from localhost ([127.0.0.1] helo=malur.postgresql.org) by malur.postgresql.org with esmtp (Exim 4.89) (envelope-from ) id 1gsgW4-0003U3-90 for pgsql-hackers@arkaria.postgresql.org; Sun, 10 Feb 2019 04:14:52 +0000 Received: from magus.postgresql.org ([2a02:c0:301:0:ffff::29]) by malur.postgresql.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_CBC_SHA1:256) (Exim 4.89) (envelope-from ) id 1gsgW2-0003Q9-6r for pgsql-hackers@lists.postgresql.org; Sun, 10 Feb 2019 04:14:52 +0000 Received: from out2-smtp.messagingengine.com ([66.111.4.26]) by magus.postgresql.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_CBC_SHA1:256) (Exim 4.89) (envelope-from ) id 1gsgVt-0002Kh-QZ for pgsql-hackers@lists.postgresql.org; Sun, 10 Feb 2019 04:14:49 +0000 Received: from compute5.internal (compute5.nyi.internal [10.202.2.45]) by mailout.nyi.internal (Postfix) with ESMTP id F340F21D4E; Sat, 9 Feb 2019 23:14:39 -0500 (EST) Received: from mailfrontend1 ([10.202.2.162]) by compute5.internal (MEProxy); Sat, 09 Feb 2019 23:14:39 -0500 DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=anarazel.de; h= date:in-reply-to:references:mime-version:content-type :content-transfer-encoding:subject:to:from:message-id; s=fm3; bh=oY69ExQuXdj2Q33j1LJzZDluRB9OprnXW5BCPhEVGoQ=; b=nTzzjRorcU7g qXod6RMVgrSSqz6APi3JINaicf0cO/rz9CWEGTGyl41dKhdPSOdClGrB88L4Gxl2 zRVAiF6Bm8MHFakCUcLdT9/2Zn21gVE67niE18xvRxhnpoEWz+IPfM6CeHoNpIgx +sq+9D461sDlpvOtk5X28gl5pacoB8zNq5XZ8+9NuoOJIGBj5NXrLYsoHUM8stZJ yw8MwG7Bels1mblCUeS6VGIBynyR+aVMUQa9kHsLjU/nkZMMSncj5K2RnIAyoGaX mZJdk9t3TECwqwVbgl+N/VVnkRHEuABFZWQbvQuXeBi23d4thtBtWwFwpnMmbN1/ z7SQMUzDxQ== DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d= messagingengine.com; h=content-transfer-encoding:content-type :date:from:in-reply-to:message-id:mime-version:references :subject:to:x-me-proxy:x-me-proxy:x-me-sender:x-me-sender :x-sasl-enc; s=fm2; bh=oY69ExQuXdj2Q33j1LJzZDluRB9OprnXW5BCPhEVG oQ=; b=Z/M7fFfMnz6nTYWwshx9vu/5wzuMfdikuWkDJH2XNLKbLQnkMaCoVnpot EKGTrwDOj5TBrl7O+Ny+/uhrofnxsHzydhuPAoF98vjEWXgBX/CO/3h2nfdhPOmY 3lTBELWUsdNecFvn5pA3mHsRLXW6eEJt38VMoydbDuA/SSYj5CeEak6ftYsiFtrv 9+K6f1kqqdYJwww+vH01zkf52xAi+U8ocC5cSNnKNrbftCJkFMcXWb5FtKy+NO8P XHzpYb9AB0jO1M36O+MyHF+VJwMn+ngHRxk3s9OqeYcv37jxbJPRxI4lgqOwRXAT 0Bd2dLTuz8tQbNFyK9wIITtmOHOAQ== X-ME-Sender: X-ME-Proxy-Cause: gggruggvucftvghtrhhoucdtuddrgedtledrleehgdeilecutefuodetggdotefrodftvf curfhrohhfihhlvgemucfhrghsthforghilhdpqfhuthenuceurghilhhouhhtmecufedt tdenucenucfjughrpefffggjfhggtgfguffvhffksehtqhhmtddtreejnecuhfhrohhmpe etnhgurhgvshcuhfhrvghunhguuceorghnughrvghssegrnhgrrhgriigvlhdruggvqeen ucfkphepuddvuddrvdeggedrudehgedrhedvnecurfgrrhgrmhepmhgrihhlfhhrohhmpe grnhgurhgvshesrghnrghrrgiivghlrdguvgenucevlhhushhtvghrufhiiigvpedt X-ME-Proxy: Received: from [172.30.3.61] (unknown [121.244.154.52]) by mail.messagingengine.com (Postfix) with ESMTPA id A1506E412F; Sat, 9 Feb 2019 23:14:38 -0500 (EST) Date: Sun, 10 Feb 2019 09:44:29 +0530 User-Agent: K-9 Mail for Android In-Reply-To: <16646.1549770618@sss.pgh.pa.us> References: <16646.1549770618@sss.pgh.pa.us> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable Subject: Re: Reporting script runtimes in pg_regress To: pgsql-hackers@lists.postgresql.org,Tom Lane From: Andres Freund Message-ID: List-Id: List-Help: List-Subscribe: List-Post: List-Owner: List-Archive: Precedence: bulk Hi, On February 10, 2019 9:20:18 AM GMT+05:30, Tom Lane wrote: >I've wondered for some time whether we couldn't make a useful >reduction in the run time of the PG regression tests by looking >for scripts that run significantly longer than others in their >parallel groups, and making an effort to trim the runtimes of >those particular scripts=2E > >The first step in that of course is to get some data, so attached >is a patch to pg_regress to cause it to print the runtime of each >script=2E This produces results like, say, > >parallel group (17 tests): circle line timetz path lseg point macaddr >macaddr8 time interval inet tstypes date box timestamp timestamptz >polygon > point =2E=2E=2E ok (35 ms) > lseg =2E=2E=2E ok (31 ms) > line =2E=2E=2E ok (23 ms) > box =2E=2E=2E ok (135 ms) > path =2E=2E=2E ok (24 ms) > polygon =2E=2E=2E ok (1256 ms) > circle =2E=2E=2E ok (20 ms) > date =2E=2E=2E ok (69 ms) > time =2E=2E=2E ok (40 ms) > timetz =2E=2E=2E ok (22 ms) > timestamp =2E=2E=2E ok (378 ms) > timestamptz =2E=2E=2E ok (378 ms) > interval =2E=2E=2E ok (50 ms) > inet =2E=2E=2E ok (56 ms) > macaddr =2E=2E=2E ok (33 ms) > macaddr8 =2E=2E=2E ok (37 ms) > tstypes =2E=2E=2E ok (62 ms) > >or on a rather slower machine, > >parallel group (8 tests): hash_part reloptions partition_info identity >partition_join partition_aggregate partition_prune indexing > identity =2E=2E=2E ok (3807 ms) > partition_join =2E=2E=2E ok (10433 ms) > partition_prune =2E=2E=2E ok (19370 ms) > reloptions =2E=2E=2E ok (1166 ms) > hash_part =2E=2E=2E ok (628 ms) > indexing =2E=2E=2E ok (22070 ms) > partition_aggregate =2E=2E=2E ok (12731 ms) > partition_info =2E=2E=2E ok (1373 ms) >test event_trigger =2E=2E=2E ok (1953 ms) >test fast_default =2E=2E=2E ok (2689 ms) >test stats =2E=2E=2E ok (1173 ms) > >Does anyone else feel that this is interesting/useful data? Yes, it does=2E I've locally been playing with parallelizing isolationtest= er's schedule, and it's quite useful for coming up with a schedule that's o= ptimized=2E Andres --=20 Sent from my Android device with K-9 Mail=2E Please excuse my brevity=2E