From debbugs-submit-bounces@debbugs.gnu.org Fri May 07 15:45:29 2021 Received: (at submit) by debbugs.gnu.org; 7 May 2021 19:45:29 +0000 Received: from localhost ([127.0.0.1]:45623 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1lf6Pg-0002UX-Kc for submit@debbugs.gnu.org; Fri, 07 May 2021 15:45:29 -0400 Received: from lists.gnu.org ([209.51.188.17]:42958) by debbugs.gnu.org with esmtp (Exim 4.84_2) (envelope-from ) id 1lf6Pe-0002UP-CC for submit@debbugs.gnu.org; Fri, 07 May 2021 15:45:27 -0400 Received: from eggs.gnu.org ([2001:470:142:3::10]:45202) by lists.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1lf6Pe-0004Zq-1J for bug-guix@gnu.org; Fri, 07 May 2021 15:45:26 -0400 Received: from mail3-relais-sop.national.inria.fr ([192.134.164.104]:10193) by eggs.gnu.org with esmtps (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.90_1) (envelope-from ) id 1lf6Pb-0004Lp-5K for bug-guix@gnu.org; Fri, 07 May 2021 15:45:25 -0400 IronPort-HdrOrdr: =?us-ascii?q?A9a23=3AF10Wz6BXyfiKYUnlHemK55DYdb4zR+YMi2TD?= =?us-ascii?q?tnoRdfUxSKClfq+V/MjzuSWE6wr5OktQ/exoSZPwI080nKQdieN9UtmfsRHdyR?= =?us-ascii?q?GVxepZnOzfKlPbakjD398Y+aB8c7VvTP3cZGIK9frS0U2XNPtl+sSO7byz7N2u?= =?us-ascii?q?tEuFGjsAV0i41XYeNu9MKDwOeDV7?= X-IronPort-AV: E=Sophos;i="5.82,282,1613430000"; d="scan'208";a="380857113" Received: from 91-160-117-201.subs.proxad.net (HELO ribbon) ([91.160.117.201]) by mail3-relais-sop.national.inria.fr with ESMTP/TLS/DHE-RSA-AES256-GCM-SHA384; 07 May 2021 21:45:16 +0200 From: =?utf-8?Q?Ludovic_Court=C3=A8s?= To: Subject: Daemon enters substitute query loop when only one of several outputs is substitutable X-Debbugs-Cc: Ricardo Wurmus , zimoun X-URL: http://www.fdn.fr/~lcourtes/ X-Revolutionary-Date: 18 =?utf-8?Q?Flor=C3=A9al?= an 229 de la =?utf-8?Q?R?= =?utf-8?Q?=C3=A9volution?= X-PGP-Key-ID: 0x090B11993D9AEBB5 X-PGP-Key: http://www.fdn.fr/~lcourtes/ludovic.asc X-PGP-Fingerprint: 3CE4 6455 8A84 FDC6 9DB4 0CFB 090B 1199 3D9A EBB5 X-OS: x86_64-pc-linux-gnu Date: Fri, 07 May 2021 21:45:15 +0200 Message-ID: <878s4qba5w.fsf@inria.fr> 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 Received-SPF: pass client-ip=192.134.164.104; envelope-from=ludovic.courtes@inria.fr; helo=mail3-relais-sop.national.inria.fr X-Spam_score_int: -41 X-Spam_score: -4.2 X-Spam_bar: ---- X-Spam_report: (-4.2 / 5.0 requ) BAYES_00=-1.9, RCVD_IN_DNSWL_MED=-2.3, RCVD_IN_MSPIKE_H3=0.001, RCVD_IN_MSPIKE_WL=0.001, SPF_HELO_NONE=0.001, SPF_PASS=-0.001 autolearn=ham autolearn_force=no X-Spam_action: no action X-Spam-Score: -1.3 (-) X-Debbugs-Envelope-To: submit X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -2.3 (--) Hi! =E2=80=98guix build /gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-0= .11.3.drv=E2=80=99 (it=E2=80=99s a derivation from d57660c54907cc6fba8b0adf6295fd2311ada6cf, Oct. 2019) currently enters an endless substitute query loop (with ci.guix.gnu.org substitutes enabled, where ci.guix.gnu.org currently lacks this substitute): --8<---------------cut here---------------start------------->8--- $ guix describe Generacio 182 May 04 2021 08:56:26 (nuna) guix fcd002c repository URL: https://git.savannah.gnu.org/git/guix.git branch: master commit: fcd002ccfa3a2bf28a9d05ab2992464afc6e5fca $ guix build /gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-0.11.3.d= rv --debug=3D3 La jena derivo estos konstruata: /gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-0.11.3.drv [at this point, loops=E2=80=A6] --8<---------------cut here---------------end--------------->8--- Looking at the corresponding =E2=80=98guix substitute --query=E2=80=99 proc= ess shows this: --8<---------------cut here---------------start------------->8--- read(0, "info /gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-ssh-0.11.3 = \n", 4096) =3D 67 openat(AT_FDCWD, "/var/guix/substitute/cache/4refhwxbjmeua2kwg2nmzhv4dg4d3d= orpjefq7kiciw2pfhaf26a/q89w5vk8lyw4jw9knd104qk3381b03dw", O_RDONLY) =3D 14 fstat(14, {st_mode=3DS_IFREG|0600, st_size=3D99, ...}) =3D 0 lseek(14, 0, SEEK_CUR) =3D 0 read(14, "(narinfo (version 2) (cache-uri \"https://ci.guix.gnu.org\") (dat= e 1620414701) (ttl 3600) (value #f))", 4096) =3D 99 close(14) =3D 0 write(4, "\n", 1) =3D 1 read(0, "info /gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-ssh-0.11.3 = \n", 4096) =3D 67 openat(AT_FDCWD, "/var/guix/substitute/cache/4refhwxbjmeua2kwg2nmzhv4dg4d3d= orpjefq7kiciw2pfhaf26a/q89w5vk8lyw4jw9knd104qk3381b03dw", O_RDONLY) =3D 14 fstat(14, {st_mode=3DS_IFREG|0600, st_size=3D99, ...}) =3D 0 lseek(14, 0, SEEK_CUR) =3D 0 read(14, "(narinfo (version 2) (cache-uri \"https://ci.guix.gnu.org\") (dat= e 1620414701) (ttl 3600) (value #f))", 4096) =3D 99 close(14) =3D 0 write(4, "\n", 1) =3D 1 read(0, "info /gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-ssh-0.11.3-= debug \n", 4096) =3D 73 openat(AT_FDCWD, "/var/guix/substitute/cache/4refhwxbjmeua2kwg2nmzhv4dg4d3d= orpjefq7kiciw2pfhaf26a/2lfh3rblh0a38s36q7xyhkac6l5kknw3", O_RDONLY) =3D 14 fstat(14, {st_mode=3DS_IFREG|0600, st_size=3D1421, ...}) =3D 0 lseek(14, 0, SEEK_CUR) =3D 0 read(14, "(narinfo (version 2) (cache-uri \"https://ci.guix.gnu.org\") (dat= e 1620414701) (ttl 7776000) (value \"StorePath: /gnu/store/2lfh3rblh0a38s36= q7xyhkac6l5kknw3-guile-ssh-0.11.3-debug\\nURL: nar/gzip/2lfh3rblh0a38s36q7x= yhkac6l5kknw3-guile-ssh-0.11.3-debug\\nCompression: gzip\\nFileSize: 347198= \\nURL: nar/lzip/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-ssh-0.11.3-debug\\n= Compression: lzip\\nFileSize: 220285\\nNarHash: sha256:0xybb41pvgxyi4fcrgml= 2qd68wh3gwv78h4r81n54w6h2dskrf08\\nNarSize: 2360032\\nReferences: 4sqps8"..= ., 4096) =3D 1421 close(14) =3D 0 write(4, "/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-ssh-0.11.3-debu= g\n", 67) =3D 67 write(4, "/gnu/store/9slm8shib43rjz1pykww0pikhb9rnfz9-guile-ssh-0.11.3.drv\= n", 65) =3D 65 write(4, "5\n", 2) =3D 2 write(4, "/gnu/store/4sqps8dczv3g7rwbdibfz6rf5jlk7w90-gcc-5.5.0-lib\n", 58)= =3D 58 write(4, "/gnu/store/9alic3caqhay3h8mx4iihpmyj6ymqpcx-guile-2.2.4\n", 56) = =3D 56 write(4, "/gnu/store/h90vnqw0nwd0hhm1l5dgxsdrigddfmq4-glibc-2.28\n", 55) = =3D 55 write(4, "/gnu/store/ljig01r4nazpjijhvmvrfwhv03ypc9a6-libssh-0.9.0\n", 57) = =3D 57 write(4, "/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-ssh-0.11.3\n", = 61) =3D 61 write(4, "220285\n", 7) =3D 7 write(4, "2360032\n", 8) =3D 8 write(4, "\n", 1) =3D 1 read(0, "info /gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-ssh-0.11.3 = \n", 4096) =3D 67 openat(AT_FDCWD, "/var/guix/substitute/cache/4refhwxbjmeua2kwg2nmzhv4dg4d3d= orpjefq7kiciw2pfhaf26a/q89w5vk8lyw4jw9knd104qk3381b03dw", O_RDONLY) =3D 14 fstat(14, {st_mode=3DS_IFREG|0600, st_size=3D99, ...}) =3D 0 lseek(14, 0, SEEK_CUR) =3D 0 read(14, "(narinfo (version 2) (cache-uri \"https://ci.guix.gnu.org\") (dat= e 1620414701) (ttl 3600) (value #f))", 4096) =3D 99 close(14) =3D 0 write(4, "\n", 1) =3D 1 --8<---------------cut here---------------end--------------->8--- The =E2=80=9Cdebug=E2=80=9D output substitute is available, but not =E2=80= =9Cout=E2=80=9D, and the daemon keeps asking alternatively for =E2=80=9Cout=E2=80=9D (twice) and =E2= =80=9Cdebug=E2=80=9D (once) in a loop. At that point, =E2=80=98guix build=E2=80=99 is stuck waiting for the reply = to its =E2=80=98build-derivations=E2=80=99 RPC, so the problem appears to be in th= e daemon. Running =E2=80=98guix build --debug=3D4 =E2=80=A6=E2=80=99 shows this: --8<---------------cut here---------------start------------->8--- | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': created | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': woken up | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': created | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': woken up | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': trying substituter | | path `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-ssh-0.11.3'= is required, but there is no substituter that can build it | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': done | | building of `/gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-= 0.11.3.drv': waitee `substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk338= 1b03dw-guile-ssh-0.11.3'' done; 1 left | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': trying substituter | | substitution of `/gnu/store/4sqps8dczv3g7rwbdibfz6rf5jlk7w90-gcc-5.= 5.0-lib': created | | substitution of `/gnu/store/4sqps8dczv3g7rwbdibfz6rf5jlk7w90-gcc-5.= 5.0-lib': woken up | | substitution of `/gnu/store/9alic3caqhay3h8mx4iihpmyj6ymqpcx-guile-= 2.2.4': created | | substitution of `/gnu/store/9alic3caqhay3h8mx4iihpmyj6ymqpcx-guile-= 2.2.4': woken up | | substitution of `/gnu/store/h90vnqw0nwd0hhm1l5dgxsdrigddfmq4-glibc-= 2.28': created | | substitution of `/gnu/store/h90vnqw0nwd0hhm1l5dgxsdrigddfmq4-glibc-= 2.28': woken up | | substitution of `/gnu/store/ljig01r4nazpjijhvmvrfwhv03ypc9a6-libssh= -0.9.0': created | | substitution of `/gnu/store/ljig01r4nazpjijhvmvrfwhv03ypc9a6-libssh= -0.9.0': woken up | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': created | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': woken up | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': goal destroyed | | substitution of `/gnu/store/4sqps8dczv3g7rwbdibfz6rf5jlk7w90-gcc-5.= 5.0-lib': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/4sqps8dczv3g7rwbdibfz6rf5jlk7w90-gcc-5.= 5.0-lib': done | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': waitee `substitution of `/gnu/store/4sqps8dczv3g7rwbdibf= z6rf5jlk7w90-gcc-5.5.0-lib'' done; 4 left | | substitution of `/gnu/store/h90vnqw0nwd0hhm1l5dgxsdrigddfmq4-glibc-= 2.28': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/h90vnqw0nwd0hhm1l5dgxsdrigddfmq4-glibc-= 2.28': done | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': waitee `substitution of `/gnu/store/h90vnqw0nwd0hhm1l5dg= xsdrigddfmq4-glibc-2.28'' done; 3 left | | substitution of `/gnu/store/9alic3caqhay3h8mx4iihpmyj6ymqpcx-guile-= 2.2.4': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/9alic3caqhay3h8mx4iihpmyj6ymqpcx-guile-= 2.2.4': done | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': waitee `substitution of `/gnu/store/9alic3caqhay3h8mx4ii= hpmyj6ymqpcx-guile-2.2.4'' done; 2 left | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': trying substituter | | path `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-ssh-0.11.3'= is required, but there is no substituter that can build it | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': done | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': waitee `substitution of `/gnu/store/q89w5vk8lyw4jw9knd10= 4qk3381b03dw-guile-ssh-0.11.3'' done; 1 left | | substitution of `/gnu/store/ljig01r4nazpjijhvmvrfwhv03ypc9a6-libssh= -0.9.0': init | | acquiring write lock on `/var/guix/temproots/18129' | | downgrading to read lock on `/var/guix/temproots/18129' | | substitution of `/gnu/store/ljig01r4nazpjijhvmvrfwhv03ypc9a6-libssh= -0.9.0': done | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': waitee `substitution of `/gnu/store/ljig01r4nazpjijhvmvr= fwhv03ypc9a6-libssh-0.9.0'' done; 0 left | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': woken up | | substitution of `/gnu/store/ljig01r4nazpjijhvmvrfwhv03ypc9a6-libssh= -0.9.0': goal destroyed | | substitution of `/gnu/store/q89w5vk8lyw4jw9knd104qk3381b03dw-guile-= ssh-0.11.3': goal destroyed | | substitution of `/gnu/store/9alic3caqhay3h8mx4iihpmyj6ymqpcx-guile-= 2.2.4': goal destroyed | | substitution of `/gnu/store/h90vnqw0nwd0hhm1l5dgxsdrigddfmq4-glibc-= 2.28': goal destroyed | | substitution of `/gnu/store/4sqps8dczv3g7rwbdibfz6rf5jlk7w90-gcc-5.= 5.0-lib': goal destroyed | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': all references realised | | some references of path `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw= 3-guile-ssh-0.11.3-debug' could not be realised | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': done | | building of `/gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-= 0.11.3.drv': waitee `substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l= 5kknw3-guile-ssh-0.11.3-debug'' done; 0 left | | building of `/gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-= 0.11.3.drv': woken up | | substitution of `/gnu/store/2lfh3rblh0a38s36q7xyhkac6l5kknw3-guile-= ssh-0.11.3-debug': goal destroyed | | building of `/gnu/store/1k8lgjs4al3gp3hri73wrbslbqa16hac-guile-ssh-= 0.11.3.drv': all outputs substituted (maybe) --8<---------------cut here---------------end--------------->8--- Note =E2=80=9Cthere is not substituter that can build it=E2=80=9D (for =E2= =80=9Cout=E2=80=9D), as well as the last line. Nix commit 4ac4f675df3da01b6d814cd328dd3219dd472ac9 (from 2018) touches code in this area. To be continued=E2=80=A6 Ludo=E2=80=99.