From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from mp0 ([2001:41d0:2:bcc0::]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits)) by ms0.migadu.com with LMTPS id QEwHCJUSomGXhAEAgWs5BA (envelope-from ) for ; Sat, 27 Nov 2021 12:12:21 +0100 Received: from aspmx1.migadu.com ([2001:41d0:2:bcc0::]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits)) by mp0 with LMTPS id IKLaA5USomEcZgAA1q6Kng (envelope-from ) for ; Sat, 27 Nov 2021 11:12:21 +0000 Received: from lists.gnu.org (lists.gnu.org [209.51.188.17]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) by aspmx1.migadu.com (Postfix) with ESMTPS id 891D234A4C for ; Sat, 27 Nov 2021 12:12:20 +0100 (CET) Received: from localhost ([::1]:48698 helo=lists1p.gnu.org) by lists.gnu.org with esmtp (Exim 4.90_1) (envelope-from ) id 1mqvcw-000428-Ft for larch@yhetil.org; Sat, 27 Nov 2021 06:12:18 -0500 Received: from eggs.gnu.org ([209.51.188.92]:55240) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1mqvcg-00041j-46 for bug-guix@gnu.org; Sat, 27 Nov 2021 06:12:02 -0500 Received: from debbugs.gnu.org ([209.51.188.43]:49730) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_128_GCM_SHA256:128) (Exim 4.90_1) (envelope-from ) id 1mqvcf-0003cA-Ro for bug-guix@gnu.org; Sat, 27 Nov 2021 06:12:01 -0500 Received: from Debian-debbugs by debbugs.gnu.org with local (Exim 4.84_2) (envelope-from ) id 1mqvcf-0006C9-NE for bug-guix@gnu.org; Sat, 27 Nov 2021 06:12:01 -0500 X-Loop: help-debbugs@gnu.org Subject: bug#51787: GC takes more than 9 hours on berlin Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= Original-Sender: "Debbugs-submit" Resent-CC: bug-guix@gnu.org Resent-Date: Sat, 27 Nov 2021 11:12:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs@gnu.org X-GNU-PR-Message: followup 51787 X-GNU-PR-Package: guix X-GNU-PR-Keywords: To: Mathieu Othacehe Received: via spool by 51787-submit@debbugs.gnu.org id=B51787.163801148823775 (code B ref 51787); Sat, 27 Nov 2021 11:12:01 +0000 Received: (at 51787) by debbugs.gnu.org; 27 Nov 2021 11:11:28 +0000 Received: from localhost ([127.0.0.1]:33043 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1mqvc8-0006BP-4Y for submit@debbugs.gnu.org; Sat, 27 Nov 2021 06:11:28 -0500 Received: from eggs.gnu.org ([209.51.188.92]:54418) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1mqvbt-0006Av-0b for 51787@debbugs.gnu.org; Sat, 27 Nov 2021 06:11:26 -0500 Received: from [2001:470:142:3::e] (port=55030 helo=fencepost.gnu.org) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1mqvbn-0003Yl-Np; Sat, 27 Nov 2021 06:11:07 -0500 DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; d=gnu.org; s=fencepost-gnu-org; h=MIME-Version:In-Reply-To:Date:References:Subject:To: From; bh=+3m2Sle1gqqBYI/7YhNSHeJ44maAx95sL3MLhqcFgzE=; b=rX4q/ZGLx2jVGAaCSRxr jD76ic/i+6chTG4wEOphTPy9p+iXUgcyH76ut1LXGEi5So9AUyViujWIt2+5WO4Rxt40UCVz0TLfR VNVp0canWMVqD3En5tHfS3MCbagVy1464gMwXkqR2JFbG/Obf8fUVJXGkjQrVlbWPrCDqsR1uoZv3 6CnygRb6QJWv9HMkMI6nLwEJblgaPK2/axJ5XuuqBJjV94ngCFJBUBlGAcy4G2NmANWalOkUp7Tj0 Ts6Z8wh97XQ/mRZz3kBdklzupCV4HvgDWFut2eSu9BHrK/VK44yrk6iR2D+QK+ByQ36+aPQbLXQdh CbVSn3Yi3vKczw==; Received: from 91-160-117-201.subs.proxad.net ([91.160.117.201]:58908 helo=ribbon) by fencepost.gnu.org with esmtpsa (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1mqvbn-0003ll-EV; Sat, 27 Nov 2021 06:11:07 -0500 From: Ludovic =?UTF-8?Q?Court=C3=A8s?= References: <87o86pegr3.fsf@gnu.org> <87zgpuhig7.fsf@gnu.org> Date: Sat, 27 Nov 2021 12:11:04 +0100 In-Reply-To: <87zgpuhig7.fsf@gnu.org> ("Ludovic =?UTF-8?Q?Court=C3=A8s?="'s message of "Tue, 23 Nov 2021 18:48:08 +0100") Message-ID: <87ilwdam5z.fsf@gnu.org> User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/27.2 (gnu/linux) MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list X-BeenThere: bug-guix@gnu.org List-Id: Bug reports for GNU Guix List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Cc: 51787@debbugs.gnu.org Errors-To: bug-guix-bounces+larch=yhetil.org@gnu.org Sender: "bug-Guix" X-Migadu-Flow: FLOW_IN X-Migadu-Country: US ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=yhetil.org; s=key1; t=1638011540; h=from:from:sender:sender:reply-to:subject:subject:date:date: message-id:message-id:to:to:cc:cc:mime-version:mime-version: content-type:content-type: content-transfer-encoding:content-transfer-encoding:resent-cc: resent-from:resent-sender:resent-message-id:in-reply-to:in-reply-to: references:references:list-id:list-help:list-unsubscribe: list-subscribe:list-post:dkim-signature; bh=+3m2Sle1gqqBYI/7YhNSHeJ44maAx95sL3MLhqcFgzE=; b=aNinkpOLIfnYr57aUo5y60e1uMe0GYVws0Aam/H7iQOvn6MhSghLLPEPQEZmtQbNXGokI4 f8WNqNCquyLwHby9D9nFIyDkN4cN/4U2ig1qNzQ3d9Dqom6cE9icdLl43/AA2207XtzuNH JJ6wZ+X9GTtYsYNmsTIr5e8+hLzNcFAntDP/g3mmdsh62Z8xuSbzQ1pV8438xb8YRaQheh R0Cu8zmNMLY2zsT3DIyETPGx/qjD9GCO60lcWfvTsNnEKxIvbYrz4KXzFh2KdESRNZ0/CZ QhBfeXXjIEbssIMtdIMQLapkpAAnTCYCg3R74JBwt7Ikrs58C3T4GFXEVNKutA== ARC-Seal: i=1; s=key1; d=yhetil.org; t=1638011540; a=rsa-sha256; cv=none; b=T/Bulh9JIMd92mtwnyUVE23MMFDeuEhx51n9gEeGzrfPCH3GCFdsLSQk64QmU5rJ41LwhT H7tC5/8/GFdo78fh7l2K50K0lH843GLeoDgy5m415JTdBEd0iWnmPDsC73d0RiULIcrq45 9WsY0E3NW1RRJC/xOHjAx7jBgu0q0qB1rqo+NHblV8DADVxFqrn7ezAUEupO4b+THa/v9A 5R8s3+iMN+tqqoEQoq5ntqps/zBZkHvGaF+yclLXByNbTCPwWITAX4zObf5wM+5UXvQ3zR wP6bKDjj3s8VnYzxrIQ4SPGevrFYRKzkhWr5DSh2ZyKyUJxCQofzQFicqxjlnA== ARC-Authentication-Results: i=1; aspmx1.migadu.com; dkim=fail ("headers rsa verify failed") header.d=gnu.org header.s=fencepost-gnu-org header.b="rX4q/ZGL"; dmarc=pass (policy=none) header.from=gnu.org; spf=pass (aspmx1.migadu.com: domain of "bug-guix-bounces+larch=yhetil.org@gnu.org" designates 209.51.188.17 as permitted sender) smtp.mailfrom="bug-guix-bounces+larch=yhetil.org@gnu.org" X-Migadu-Spam-Score: -3.61 Authentication-Results: aspmx1.migadu.com; dkim=fail ("headers rsa verify failed") header.d=gnu.org header.s=fencepost-gnu-org header.b="rX4q/ZGL"; dmarc=pass (policy=none) header.from=gnu.org; spf=pass (aspmx1.migadu.com: domain of "bug-guix-bounces+larch=yhetil.org@gnu.org" designates 209.51.188.17 as permitted sender) smtp.mailfrom="bug-guix-bounces+larch=yhetil.org@gnu.org" X-Migadu-Queue-Id: 891D234A4C X-Spam-Score: -3.61 X-Migadu-Scanner: scn0.migadu.com X-TUID: m9l8aFVqgu11 Ludovic Court=C3=A8s skribis: > =E2=80=A2 Stracing the session=E2=80=99s guix-daemon process during GC = suggests that > most of the time goes into I/O from =E2=80=98db.sqlite=E2=80=99. It= =E2=80=99s not > surprising because that GC phase is basically about browsing the > database, but it does seem to take a little too long for each store > item. Stracing the client shows that the daemon spends several seconds on a single store item occasionally: --8<---------------cut here---------------start------------->8--- read(27, "gmlo\0\0\0\0", 8) =3D 8 <0.363064> read(27, "c\0\0\0\0\0\0\0", 8) =3D 8 <0.000013> read(27, "[95%] deleting '/gnu/store/p6r2jjy6frp682z3x94nvnmdh71p1p58-ecl-q= uicksearch-0.01"..., 104) =3D 104 <0.000010> write(2, "[95%] deleting '/gnu/store/p6r2jjy6frp682z3x94nvnmdh71p1p58-ecl-q= uicksearch-0.01"..., 99) =3D 99 <0.000019> read(27, "gmlo\0\0\0\0", 8) =3D 8 <0.017863> read(27, "^\0\0\0\0\0\0\0", 8) =3D 8 <0.000019> read(27, "[95%] deleting '/gnu/store/v6zd510kfmqd8j4w7q3zy9bid1fj96dk-sheph= erd-guix-daemon"..., 96) =3D 96 <0.000007> write(2, "[95%] deleting '/gnu/store/v6zd510kfmqd8j4w7q3zy9bid1fj96dk-sheph= erd-guix-daemon"..., 94) =3D 94 <0.000012> read(27, "gmlo\0\0\0\0", 8) =3D 8 <5.861071> read(27, "T\0\0\0\0\0\0\0", 8) =3D 8 <0.000061> read(27, "[95%] deleting '/gnu/store/0hpwig8cwdnzygjjzs9zjbxicvhif2vv-rust-= bitvec-0.19.4.d"..., 88) =3D 88 <0.000087> write(2, "[95%] deleting '/gnu/store/0hpwig8cwdnzygjjzs9zjbxicvhif2vv-rust-= bitvec-0.19.4.d"..., 84) =3D 84 <0.000033> --8<---------------cut here---------------end--------------->8--- (Notice =E2=80=98read=E2=80=99 taking 5.9s above.) Ludo=E2=80=99.