GNU logs - #49862, boring messages

Message sent to bug-guix@HIDDEN:

Subject: bug#49862: While downloading substitutes: Wrong type argument in position 1 (expecting struct): #f
Resent-From: Maxim Cournoyer <maxim.cournoyer@HIDDEN>
Original-Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
Resent-CC: bug-guix@HIDDEN
Resent-Date: Wed, 04 Aug 2021 02:46:02 +0000
Resent-Message-ID: <handler.49862.B.162804510623382 <at> debbugs.gnu.org>
Resent-Sender: help-debbugs@HIDDEN
To: 49862 <at> debbugs.gnu.org
From: Maxim Cournoyer <maxim.cournoyer@HIDDEN>
Date: Tue, 03 Aug 2021 22:44:58 -0400
Message-ID: <87a6ly7wt1.fsf@HIDDEN>
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
Received-SPF: pass client-ip=2607:f8b0:4864:20::734;
 envelope-from=maxim.cournoyer@HIDDEN; helo=mail-qk1-x734.google.com
X-Spam_score_int: -20
X-Spam_score: -2.1
X-Spam_bar: --
X-Spam_report: (-2.1 / 5.0 requ) BAYES_00=-1.9, DKIM_SIGNED=0.1,
 DKIM_VALID=-0.1, DKIM_VALID_AU=-0.1, DKIM_VALID_EF=-0.1, FREEMAIL_FROM=0.001,
 RCVD_IN_DNSWL_NONE=-0.0001, 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-BeenThere: debbugs-submit <at> debbugs.gnu.org
X-Mailman-Version: 2.1.18
Precedence: list
List-Id: <debbugs-submit.debbugs.gnu.org>
List-Unsubscribe: <https://debbugs.gnu.org/cgi-bin/mailman/options/debbugs-submit>, 
 <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=unsubscribe>
List-Archive: <https://debbugs.gnu.org/cgi-bin/mailman/private/debbugs-submit/>
List-Post: <mailto:debbugs-submit <at> debbugs.gnu.org>
List-Help: <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=help>
List-Subscribe: <https://debbugs.gnu.org/cgi-bin/mailman/listinfo/debbugs-submit>, 
 <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=subscribe>
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
X-Spam-Score: -2.3 (--)

Hello,

This occurred while trying to build an updated fontconfig on the
core-updates branch:

--8<---------------cut here---------------start------------->8---
successfully built /gnu/store/dh7wr4laxg5r8x22iv2ydbh8krcm6782-libuninamesl=
ist-20200313.drv
The following builds are still in progress:
  /gnu/store/mzw4s9y67cp9i1mylqkiyrpxpyaq5f7s-libnftnl-1.2.0.drv
  /gnu/store/vn2pgkxz1lhvdmzv63crlxvz913v9q3i-libungif-4.1.4.drv
  /gnu/store/yjq9xi300kwpcdca9b38r0fjmvl8q95c-m4-1.4.18.tar.xz.drv
  /gnu/store/5wg445g4y00jwxvga7rk4fk4vmv6hl8d-lzo-2.10.drv
  /gnu/store/hicph1jxczzacyy3xc4wqbjfqdskxwnn-libxml2-2.9.12.tar.xz.drv
  /gnu/store/gda40q0dd10hr0fm4sffzgc9zv24mb0s-libxml2-2.9.12.tar.xz.drv
  /gnu/store/8ir67jqrzwz62s522xi19xlq0yik417f-libtasn1-4.17.0.drv
  /gnu/store/mr9639s4z2fsvcwmw8mw17j9b5f6l4gh-lxml-4.6.3.tar.xz.drv
  /gnu/store/96qnk27khjc25rbdz2gxr22sqgmh4lq9-libxslt-1.1.34.tar.xz.drv
  /gnu/store/jxvirmrqxhfp530vggm6bcgq8rs6ya6y-libtool-2.4.6.tar.xz.drv
  /gnu/store/flmxhxmkj6vbpg6pgh57xp390bxwzqj7-guile-3.0.7.drv
  /gnu/store/sl9k1cdgp1g6hk796sd1pra1w88vnybc-bash-minimal-5.1.8.drv
  /gnu/store/a2zj0nybbs7ydgp30da8vxbgf3s9ndfv-docbook-xsl-1.79.2.tar.xz.drv
  /gnu/store/8rc15lklpf7xyyr57a8mvl2ybvrj5h3q-c-ares-1.17.1.drv
  /gnu/store/4s3kciwd65syrvk3ryvvyk3xydx43mw3-cairo-1.16.0.tar.xz.drv

substituting /gnu/store/yqvsc5brbzpk1m7zr8bnyr66kz9q625a-nasm-2.15.05.tar.x=
z...
substituting /gnu/store/4fliaxdz084ijlzb6d98sgg76dmz9xp6-net-tools-1.60-0.4=
79bb4a.zip...
substituting /gnu/store/js6wj0pmd4lqnnkys7f9az4gcm8szfmi-nettle-3.7.3.tar.g=
z...
substituting /gnu/store/lz5qmr8bv3wwmja3vw59yjydiy66ni3a-nghttp2-1.44.0.tar=
.xz...
process 30234 acquired build slot '/var/guix/offload/127.0.0.1:6666/1'
normalized load on machine '127.0.0.1' is 0.17
building /gnu/store/9xadqmcq1z02x93k7c5xpwbwd4a7f1rk-apr-1.6.5.drv...
eckout 50269 50269
Backtrace:
          19 (apply-smob/0 #<thunk 7fd0dfaedf60>)
In ice-9/boot-9.scm:
    724:2 18 (call-with-prompt _ _ #<procedure default-prompt-handler (k pr=
oc)>)
In ice-9/eval.scm:
    619:8 17 (_ #(#(#<directory (guile-user) 7fd0dfae7c80>)))
In guix/ui.scm:
   2185:7 16 (run-guix . _)
  2148:10 15 (run-guix-command _ . _)
In ice-9/boot-9.scm:
  1752:10 14 (with-exception-handler _ _ #:unwind? _ #:unwind-for-type _)
In guix/status.scm:
    800:4 13 (call-with-status-report _ _)
In ice-9/boot-9.scm:
  1752:10 12 (with-exception-handler _ _ #:unwind? _ #:unwind-for-type _)
In guix/store.scm:
   658:37 11 (thunk)
   1320:8 10 (call-with-build-handler _ _)
   1320:8  9 (call-with-build-handler #<procedure 7fd0dd3fdfc0 at guix/ui.s=
cm:1155:2 (continue store =E2=80=A6> =E2=80=A6)
In guix/scripts/build.scm:
   699:26  8 (_)
In guix/store.scm:
  1388:15  7 (_ #<store-connection 256.99 7fd0dfa699b0> _ _)
   759:13  6 (process-stderr _ _)
In unknown file:
           5 (display "eckout 50269 50269\n@ download-succeeded /gnu/store/=
1i7b2hrywrp90w5dd9nq6dw788=E2=80=A6" =E2=80=A6)
In guix/status.scm:
   723:16  4 (write! _ _ _)
   636:15  3 (_ (download-succeeded "/gnu/store/1i7b2hrywrp90w5dd9nq6dw7888=
wzqkq-mallard-ducktype-=E2=80=A6" =E2=80=A6) =E2=80=A6)
   272:33  2 (compute-status _ #<<build-status> building: (#<<build> deriva=
tion: "/gnu/store/9xadqmcq=E2=80=A6> =E2=80=A6)
In ice-9/boot-9.scm:
  1685:16  1 (raise-exception _ #:continuable? _)
  1685:16  0 (raise-exception _ #:continuable? _)

ice-9/boot-9.scm:1685:16: In procedure raise-exception:
In procedure struct-vtable: Wrong type argument in position 1 (expecting
struct): #f
--8<---------------cut here---------------end--------------->8---

Thanks,

Maxim

Message sent:

Content-Disposition: inline
Content-Transfer-Encoding: quoted-printable
MIME-Version: 1.0
X-Mailer: MIME-tools 5.505 (Entity 5.505)
Content-Type: text/plain; charset=utf-8
X-Loop: help-debbugs@HIDDEN
From: help-debbugs@HIDDEN (GNU bug Tracking System)
To: Maxim Cournoyer <maxim.cournoyer@HIDDEN>
Subject: bug#49862: Acknowledgement (While downloading substitutes: Wrong
 type argument in position 1 (expecting struct): #f)
Message-ID: <handler.49862.B.162804510623382.ack <at> debbugs.gnu.org>
References: <87a6ly7wt1.fsf@HIDDEN>
X-Gnu-PR-Message: ack 49862
X-Gnu-PR-Package: guix
Reply-To: 49862 <at> debbugs.gnu.org
Date: Wed, 04 Aug 2021 02:46:02 +0000

Thank you for filing a new bug report with debbugs.gnu.org.

This is an automatically generated reply to let you know your message
has been received.

Your message is being forwarded to the package maintainers and other
interested parties for their attention; they will reply in due course.

Your message has been sent to the package maintainer(s):
 bug-guix@HIDDEN

If you wish to submit further information on this problem, please
send it to 49862 <at> debbugs.gnu.org.

Please do not send mail to help-debbugs@HIDDEN unless you wish
to report a problem with the Bug-tracking system.

--=20
49862: http://debbugs.gnu.org/cgi/bugreport.cgi?bug=3D49862
GNU Bug Tracking System
Contact help-debbugs@HIDDEN with problems

Message received at control <at> debbugs.gnu.org:

Received: (at control) by debbugs.gnu.org; 4 Aug 2021 08:26:52 +0000
From debbugs-submit-bounces <at> debbugs.gnu.org Wed Aug 04 04:26:52 2021
Received: from localhost ([127.0.0.1]:42659 helo=debbugs.gnu.org)
	by debbugs.gnu.org with esmtp (Exim 4.84_2)
	(envelope-from <debbugs-submit-bounces <at> debbugs.gnu.org>)
	id 1mBCEm-0002u0-Il
	for submit <at> debbugs.gnu.org; Wed, 04 Aug 2021 04:26:52 -0400
Received: from eggs.gnu.org ([209.51.188.92]:53700)
 by debbugs.gnu.org with esmtp (Exim 4.84_2)
 (envelope-from <ludo@HIDDEN>) id 1mBCEl-0002tm-Ae
 for control <at> debbugs.gnu.org; Wed, 04 Aug 2021 04:26:51 -0400
Received: from fencepost.gnu.org ([2001:470:142:3::e]:52234)
 by eggs.gnu.org with esmtp (Exim 4.90_1)
 (envelope-from <ludo@HIDDEN>) id 1mBCEg-0005sU-4T
 for control <at> debbugs.gnu.org; Wed, 04 Aug 2021 04:26:46 -0400
Received: from [2001:660:6102:320:e120:2c8f:8909:cdfe] (port=43020 helo=ribbon)
 by fencepost.gnu.org with esmtpsa (TLS1.2:ECDHE_RSA_AES_256_GCM_SHA384:256)
 (Exim 4.90_1) (envelope-from <ludo@HIDDEN>) id 1mBCEf-0002Wz-SE
 for control <at> debbugs.gnu.org; Wed, 04 Aug 2021 04:26:46 -0400
Date: Wed, 04 Aug 2021 10:26:44 +0200
Message-Id: <87a6lxsji3.fsf@HIDDEN>
To: control <at> debbugs.gnu.org
From: =?utf-8?Q?Ludovic_Court=C3=A8s?= <ludo@HIDDEN>
Subject: control message for bug #49862
MIME-version: 1.0
Content-type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
X-Spam-Score: -2.3 (--)
X-Debbugs-Envelope-To: control
X-BeenThere: debbugs-submit <at> debbugs.gnu.org
X-Mailman-Version: 2.1.18
Precedence: list
List-Id: <debbugs-submit.debbugs.gnu.org>
List-Unsubscribe: <https://debbugs.gnu.org/cgi-bin/mailman/options/debbugs-submit>, 
 <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=unsubscribe>
List-Archive: <https://debbugs.gnu.org/cgi-bin/mailman/private/debbugs-submit/>
List-Post: <mailto:debbugs-submit <at> debbugs.gnu.org>
List-Help: <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=help>
List-Subscribe: <https://debbugs.gnu.org/cgi-bin/mailman/listinfo/debbugs-submit>, 
 <mailto:debbugs-submit-request <at> debbugs.gnu.org?subject=subscribe>
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
X-Spam-Score: -3.3 (---)

severity 49862 important
quit

Message sent to bug-guix@HIDDEN:

Subject: bug#49862: While downloading substitutes: Wrong type argument in position 1 (expecting struct): #f
Resent-From: Ludovic =?UTF-8?Q?Court=C3=A8s?= <ludo@HIDDEN>
Original-Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>
Resent-CC: bug-guix@HIDDEN
Resent-Date: Wed, 04 Aug 2021 08:41:02 +0000
Resent-Message-ID: <handler.49862.B49862.162806640620804 <at> debbugs.gnu.org>
Resent-Sender: help-debbugs@HIDDEN
To: Maxim Cournoyer <maxim.cournoyer@HIDDEN>
Cc: 49862 <at> debbugs.gnu.org
From: Ludovic =?UTF-8?Q?Court=C3=A8s?= <ludo@HIDDEN>
References: <87a6ly7wt1.fsf@HIDDEN>
Date: Wed, 04 Aug 2021 10:39:57 +0200
In-Reply-To: <87a6ly7wt1.fsf@HIDDEN> (Maxim Cournoyer's message of "Tue, 03
 Aug 2021 22:44:58 -0400")
Message-ID: <871r79siw2.fsf@HIDDEN>
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
Precedence: list
Errors-To: debbugs-submit-bounces <at> debbugs.gnu.org
Sender: "Debbugs-submit" <debbugs-submit-bounces <at> debbugs.gnu.org>

Hi Maxim,

Maxim Cournoyer <maxim.cournoyer@HIDDEN> skribis:

> This occurred while trying to build an updated fontconfig on the
> core-updates branch:
>
> successfully built /gnu/store/dh7wr4laxg5r8x22iv2ydbh8krcm6782-libuniname=
slist-20200313.drv
> The following builds are still in progress:
>   /gnu/store/mzw4s9y67cp9i1mylqkiyrpxpyaq5f7s-libnftnl-1.2.0.drv
>   /gnu/store/vn2pgkxz1lhvdmzv63crlxvz913v9q3i-libungif-4.1.4.drv
>   /gnu/store/yjq9xi300kwpcdca9b38r0fjmvl8q95c-m4-1.4.18.tar.xz.drv
>   /gnu/store/5wg445g4y00jwxvga7rk4fk4vmv6hl8d-lzo-2.10.drv
>   /gnu/store/hicph1jxczzacyy3xc4wqbjfqdskxwnn-libxml2-2.9.12.tar.xz.drv
>   /gnu/store/gda40q0dd10hr0fm4sffzgc9zv24mb0s-libxml2-2.9.12.tar.xz.drv
>   /gnu/store/8ir67jqrzwz62s522xi19xlq0yik417f-libtasn1-4.17.0.drv
>   /gnu/store/mr9639s4z2fsvcwmw8mw17j9b5f6l4gh-lxml-4.6.3.tar.xz.drv
>   /gnu/store/96qnk27khjc25rbdz2gxr22sqgmh4lq9-libxslt-1.1.34.tar.xz.drv
>   /gnu/store/jxvirmrqxhfp530vggm6bcgq8rs6ya6y-libtool-2.4.6.tar.xz.drv
>   /gnu/store/flmxhxmkj6vbpg6pgh57xp390bxwzqj7-guile-3.0.7.drv
>   /gnu/store/sl9k1cdgp1g6hk796sd1pra1w88vnybc-bash-minimal-5.1.8.drv
>   /gnu/store/a2zj0nybbs7ydgp30da8vxbgf3s9ndfv-docbook-xsl-1.79.2.tar.xz.d=
rv
>   /gnu/store/8rc15lklpf7xyyr57a8mvl2ybvrj5h3q-c-ares-1.17.1.drv
>   /gnu/store/4s3kciwd65syrvk3ryvvyk3xydx43mw3-cairo-1.16.0.tar.xz.drv
>
> substituting /gnu/store/yqvsc5brbzpk1m7zr8bnyr66kz9q625a-nasm-2.15.05.tar=
.xz...
> substituting /gnu/store/4fliaxdz084ijlzb6d98sgg76dmz9xp6-net-tools-1.60-0=
.479bb4a.zip...
> substituting /gnu/store/js6wj0pmd4lqnnkys7f9az4gcm8szfmi-nettle-3.7.3.tar=
.gz...
> substituting /gnu/store/lz5qmr8bv3wwmja3vw59yjydiy66ni3a-nghttp2-1.44.0.t=
ar.xz...
> process 30234 acquired build slot '/var/guix/offload/127.0.0.1:6666/1'
> normalized load on machine '127.0.0.1' is 0.17
> building /gnu/store/9xadqmcq1z02x93k7c5xpwbwd4a7f1rk-apr-1.6.5.drv...
> eckout 50269 50269
> Backtrace:
>           19 (apply-smob/0 #<thunk 7fd0dfaedf60>)
> In ice-9/boot-9.scm:
>     724:2 18 (call-with-prompt _ _ #<procedure default-prompt-handler (k =
proc)>)
> In ice-9/eval.scm:
>     619:8 17 (_ #(#(#<directory (guile-user) 7fd0dfae7c80>)))
> In guix/ui.scm:
>    2185:7 16 (run-guix . _)
>   2148:10 15 (run-guix-command _ . _)
> In ice-9/boot-9.scm:
>   1752:10 14 (with-exception-handler _ _ #:unwind? _ #:unwind-for-type _)
> In guix/status.scm:
>     800:4 13 (call-with-status-report _ _)
> In ice-9/boot-9.scm:
>   1752:10 12 (with-exception-handler _ _ #:unwind? _ #:unwind-for-type _)
> In guix/store.scm:
>    658:37 11 (thunk)
>    1320:8 10 (call-with-build-handler _ _)
>    1320:8  9 (call-with-build-handler #<procedure 7fd0dd3fdfc0 at guix/ui=
.scm:1155:2 (continue store =E2=80=A6> =E2=80=A6)
> In guix/scripts/build.scm:
>    699:26  8 (_)
> In guix/store.scm:
>   1388:15  7 (_ #<store-connection 256.99 7fd0dfa699b0> _ _)
>    759:13  6 (process-stderr _ _)
> In unknown file:
>            5 (display "eckout 50269 50269\n@ download-succeeded /gnu/stor=
e/1i7b2hrywrp90w5dd9nq6dw788=E2=80=A6" =E2=80=A6)
> In guix/status.scm:
>    723:16  4 (write! _ _ _)
>    636:15  3 (_ (download-succeeded "/gnu/store/1i7b2hrywrp90w5dd9nq6dw78=
88wzqkq-mallard-ducktype-=E2=80=A6" =E2=80=A6) =E2=80=A6)
>    272:33  2 (compute-status _ #<<build-status> building: (#<<build> deri=
vation: "/gnu/store/9xadqmcq=E2=80=A6> =E2=80=A6)
> In ice-9/boot-9.scm:
>   1685:16  1 (raise-exception _ #:continuable? _)
>   1685:16  0 (raise-exception _ #:continuable? _)
>
> ice-9/boot-9.scm:1685:16: In procedure raise-exception:
> In procedure struct-vtable: Wrong type argument in position 1 (expecting
> struct): #f

That reminds me of <https://issues.guix.gnu.org/43518> though it seems
to be a bit different.

The context of status.scm:272:33 is this:

--8<---------------cut here---------------start------------->8---
    (('download-succeeded item uri (=3D string->number size))
     (let ((current (find (matching-download item)
                          (build-status-downloading status))))
       (build-status
        (inherit status)
        (downloading (delq current (build-status-downloading status)))
        (downloads-completed
         (cons (download item uri
                         #:size size
                         #:start (download-start current)  ;<- HERE
                         #:transferred size
                         #:end (current-time time-monotonic))
               (build-status-downloads-completed status))))))
--8<---------------cut here---------------end--------------->8---

Here, =E2=80=98current=E2=80=99 is #f, hence the type error.  We could be d=
efensive and
check whether =E2=80=98current=E2=80=99 is #f before proceeding; however, i=
t=E2=80=99s =E2=80=9Cnot
supposed to happen=E2=80=9D as that indicates an inconsistency: that =E2=80=
=98status=E2=80=99
doesn=E2=80=99t match reality.

The root cause is probably that (guix status) missed a trace, one of
these =E2=80=9C@ download-progress =E2=80=A6=E2=80=9D lines, which would pr=
obably be a bug in
=E2=80=98build-event-output-port=E2=80=99.  Or it could be that the output =
from
guix-daemon is already intermingled, though that=E2=80=99s again not suppos=
ed to
happen.

Perhaps the best course of action here would be to hammer
=E2=80=98build-event-output-port=E2=80=99 with semi-randomly generated data=
 until we can
reproduce the bug, assuming this is where it takes place.

Thanks,
Ludo=E2=80=99.

Last modified: Wed, 4 Aug 2021 08:45:02 UTC

GNU bug tracking system
Copyright (C) 1999 Darren O. Benham, 1997 nCipher Corporation Ltd, 1994-97 Ian Jackson.