GNU bug report logs - #53027
Backtrace with `guix pull --debug=2`

Previous Next

Package: guix;

Reported by: Oskar Berndal <o.berndal <at> gmail.com>

Date: Wed, 5 Jan 2022 15:57:03 UTC

Severity: normal

Done: Andreas Enge <andreas <at> enge.fr>

Bug is archived. No further changes may be made.

To add a comment to this bug, you must first unarchive it, by sending
a message to control AT debbugs.gnu.org, with unarchive 53027 in the body.
You can then email your comments to 53027 AT debbugs.gnu.org in the normal way.

Toggle the display of automated, internal messages from the tracker.

View this report as an mbox folder, status mbox, maintainer mbox

Report forwarded to bug-guix <at> gnu.org:
bug#53027; Package guix. (Wed, 05 Jan 2022 15:57:03 GMT) Full text and rfc822 format available.
Acknowledgement sent to Oskar Berndal <o.berndal <at> gmail.com>:
New bug report received and forwarded. Copy sent to bug-guix <at> gnu.org. (Wed, 05 Jan 2022 15:57:03 GMT) Full text and rfc822 format available.

Message #5 received at submit <at> debbugs.gnu.org (full text, mbox):

From: Oskar Berndal <o.berndal <at> gmail.com>
To: bug-guix <at> gnu.org
Subject: Backtrace with `guix pull --debug=2`
Date: Wed, 5 Jan 2022 16:19:39 +0100

I tried running `guix pull` with the `--debug=2` flag. However, it led
a backtrace, that I include at the bottom. I tried the command twice
with the same backtrace, and then I tried without the debug flag,
simply running `guix pull`. This worked without error. Trying to run
with the debug flag again also worked immediately after that.

Prior to all of this, I made a system reconfiguration where I changed
the uid but not the name of a user. I needed to manually change the
ownership of some files to the new user id so that guix wouldn't trip
at some point. Maybe that's a clue, that it is due to some other files
being of wrong ownership (but not producing an error message I can
understand this time).

Other info:
- guix 1.3.0
- Installed on WSL

These are all things I can think of to add at the moment, please let
me know if there is something else that might be helpful if I can
provide.
Cheers,
Oskar

```
$ guix pull --debug=2
...
building path(s)
`/gnu/store/z0i0wwc7wvch9a8n0gd47gkay9lj1qb0-module-import-compiled'
|   @ build-started
/gnu/store/5m4pr3gskpzgshza09ashiysz4a3pd4v-module-import-compiled.drv
- x86_64-linux /var/log/guix/drvs/5m//4pr3gskpzgshza09ashiysz4a3pd4v-module-import-compiled.drv.bz2
3102
Backtrace:
In guix/channels.scm:
    890:2 19 (_ _)
In ./guix/monads.scm:
    471:9 18 (_ _)
In guix/store.scm:
   1956:8 17 (_ _)
In guix/channels.scm:
   641:14 16 (_ #<store-connection 256.99 7efc327c44b0>)
In guix/store.scm:
  2119:24 15 (run-with-store #<store-connection 256.99 7efc327c44b0> …)
   1320:8 14 (call-with-build-handler _ _)
   1320:8 13 (call-with-build-handler #<procedure 7efc32b9d6d0 at g…> …)
In guix/channels.scm:
   656:14 12 (_)
In guix/store.scm:
  2119:24 11 (run-with-store #<store-connection 256.99 7efc327c44b0> …)
In unknown file:
          10 (_ #<procedure 7efc1b421000 at ice-9/eval.scm:330:13 ()> …)
In guix/store.scm:
  1991:38  9 (_ #<store-connection 256.99 7efc2761b820>)
  1417:15  8 (_ #<store-connection 256.99 7efc2761b820> _ _)
   759:13  7 (process-stderr _ _)
In unknown file:
           6 (display "@ build-log 3102 39\n[ 1/78] Loading './gcry…" …)
In guix/status.scm:
   745:19  5 (write! _ _ _)
In srfi/srfi-1.scm:
   460:18  4 (fold #<procedure 7efc3281ac40 at guix/status.scm:635:…> …)
In guix/status.scm:
   636:15  3 (_ (build-log 3102 "[ 1/78] Loading './gcrypt/hash.s…") #)
   179:23  2 (update-build #<<build-status> building: () downloadin…> …)
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
```
Information forwarded to bug-guix <at> gnu.org:
bug#53027; Package guix. (Wed, 05 Jan 2022 21:21:02 GMT) Full text and rfc822 format available.

Message #8 received at 53027 <at> debbugs.gnu.org (full text, mbox):

From: Maxime Devos <maximedevos <at> telenet.be>
To: Oskar Berndal <o.berndal <at> gmail.com>, 53027 <at> debbugs.gnu.org
Subject: Re: bug#53027: Backtrace with `guix pull --debug=2`
Date: Wed, 05 Jan 2022 21:20:12 +0000

[Message part 1 (text/plain, inline)]
Oskar Berndal schreef op wo 05-01-2022 om 16:19 [+0100]:
I tried running `guix pull` with the `--debug=2` flag. However, it led
a backtrace, that I include at the bottom. I tried the command twice
with the same backtrace, and then I tried without the debug flag,
simply running `guix pull`. This worked without error. Trying to run
with the debug flag again also worked immediately after that. [...]

I can reproduce. Tip: setting COLUMN to a huge number prevents these _
in backtraces, making the backtrace more informative:

$ COLUMNS=9000 guix pull --debug=2

Anyway, here's extra debug information, to be investigated:

10,6 MB zal binnengehaald worden
fetching path `/gnu/store/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual'...
 guix-manual                                                                                                              4.3MiB/s 00:02 | 7.6MiB transferred
fetching path `/gnu/store/3d9cyp999jsxamz018hiz20av600syrf-guix-extra'...
 guix-extra  2.9MiB                                                                                                2.5MiB/s 00:01 [##################] 100.0%
fetching path `/gnu/store/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix'...
 guix-locale-guix  306KiB                                                                                        515.0MiB/s 00:00 [##################] 100.0%
building path(s) `/gnu/store/xxhjcnw24vqhsh7zngz19drkpr24p933-config.scm'
|   @ build-started /gnu/store/w7y0m9g09wqnzlwzzdf1kw6ayq42a54g-config.scm.drv - x86_64-linux /var/log/guix/drvs/w7//y0m9g09wqnzlwzzdf1kw6ayq42a54g-config.scm.drv.bz2 26258
scanning for references inside `/gnu/store/xxhjcnw24vqhsh7zngz19drkpr24p933-config.scm'
building path(s) `/gnu/store/a57mqs7dgpmgkrbsif4fg340x3r9g5r7-guix-extra-modules'
|   @ build-started /gnu/store/y4plqs9vq1zbqsgassk1j3qznjkfnj65-guix-extra-modules.drv - x86_64-linux /var/log/guix/drvs/y4//plqs9vq1zbqsgassk1j3qznjkfnj65-guix-extra-modules.drv.bz2 26269
scanning for references inside `/gnu/store/a57mqs7dgpmgkrbsif4fg340x3r9g5r7-guix-extra-modules'
building path(s) `/gnu/store/v6pwv3gpb8gphn0smw2fr3zn1d4a2689-guix-config-source'
|   @ build-started /gnu/store/px2f4syh8hh2152a9bsff07a1hk07rb5-guix-config-source.drv - x86_64-linux /var/log/guix/drvs/px//2f4syh8hh2152a9bsff07a1hk07rb5-guix-config-source.drv.bz2 26280
scanning for references inside `/gnu/store/v6pwv3gpb8gphn0smw2fr3zn1d4a2689-guix-config-source'
building path(s) `/gnu/store/nqn5f7pj69hbkdbpw5y9phny3badpbjk-guix-packages-base'
|   @ build-started /gnu/store/88bx7pj1czzhbnhsb0x2kn9zv6ny83ap-guix-packages-base.drv - x86_64-linux /var/log/guix/drvs/88//bx7pj1czzhbnhsb0x2kn9zv6ny83ap-guix-packages-base.drv.bz2 26293
Backtrace:
In guix/store.scm:
   658:37 19 (thunk)
In guix/status.scm:
    802:4 18 (call-with-status-report _ _)
In guix/store.scm:
   1343:3 17 (_)
   1320:8 16 (call-with-build-handler _ _)
   1320:8 15 (call-with-build-handler #<procedure 7fc90f307060 at guix/ui.scm:1156:2 (continue store things mode)> _)
In guix/build/syscalls.scm:
   1423:3 14 (_)
   1390:4 13 (call-with-file-lock/no-wait _ _ _)
In guix/store.scm:
  2123:24 12 (run-with-store #<store-connection 256.99 7fc90f2b8190> _ #:guile-for-build _ #:system _ #:target _)
In guix/scripts/pull.scm:
    428:4 11 (_ _)
In guix/store.scm:
  1995:38 10 (_ #<store-connection 256.99 7fc8fdc73c30>)
In guix/scripts/package.scm:
   166:13  9 (build-and-use-profile #<store-connection 256.99 7fc8fdc73c30> "/var/guix/profiles/per-user/regulator/current-guix" #<<manifest> entries: (#<<manifest-entry> name: "guix" version: "f5da992" output: "out" item: #<derivation /gnu/store/jh87xsvgykf67am1l61llqfmbnpwxqa2-guix-f5da992b1.drv => /gnu/store/1m0cganbbvk1kwpppli9gm3mnxrr1h9v-guix-f5da992b1 7fc9023d5050> dependencies: () search-paths: () parent: #<promise #<procedure 7fc90f64c0f0 at guix/channels.scm:879:6 ()>> properties: ((source (repository (version 0) (url "https://git.savannah.gnu.org/git/guix.git") (branch "master") (commit "f5da992b16591b6dc358aa5224fa095738471585") (name guix) (introduction (channel-introduction (version 0) (commit "9edb3f66fd807b096b48283debdcddccfea34bad") (signer "BBB0 2DDF 2CEA F6A8 0D1D  E643 A2A0 6DF2 A33A 54FA"))))))>)> #:hooks _ #:allow-collisions? _ #:bootstrap? _)
In guix/store.scm:
  1421:15  8 (_ #<store-connection 256.99 7fc8fdc73c30> _ _)
   759:13  7 (process-stderr _ _)
In unknown file:
           6 (display "@ build-log 26293 41\n\r[  0/654] loading...\t  0.0% of 327 files" #<output: string 7fc90f2ed700>)
In guix/status.scm:
   747:19  5 (write! _ _ _)
In srfi/srfi-1.scm:
   460:18  4 (fold #<procedure 7fc90f30ed40 at guix/status.scm:637:2 (event status)> #<<build-status> building: () downloading: () builds-completed: () downloads-completed: (#<<download> item: "/gnu/store/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix" uri: "https://ci.guix.gnu.org/nar/zstd/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix" size: 313201 start: #<time type: time-monotonic nanosecond: 652962000 second: 1641417239> end: #<time type: time-monotonic nanosecond: 653772000 second: 1641417239> transferred: 313201> #<<download> item: "/gnu/store/3d9cyp999jsxamz018hiz20av600syrf-guix-extra" uri: "https://ci.guix.gnu.org/nar/zstd/3d9cyp999jsxamz018hiz20av600syrf-guix-extra" size: 3035344 start: #<time type: time-monotonic nanosecond: 217310000 second: 1641417238> end: #<time type: time-monotonic nanosecond: 373795000 second: 1641417239> transferred: 3035344> #<<download> item: "/gnu/store/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual" uri: "https://ci.guix.gnu.org/nar/lzip/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual" size: 7944251 start: #<time type: time-monotonic nanosecond: 149866000 second: 1641417236> end: #<time type: time-monotonic nanosecond: 895408000 second: 1641417237> transferred: 7944251>)> ((build-log 26293 "\r[  0/654] loading...\t  0.0% of 327 files")))
In guix/status.scm:
   638:15  3 (_ (build-log 26293 "\r[  0/654] loading...\t  0.0% of 327 files") #<<build-status> building: () downloading: () builds-completed: () downloads-completed: (#<<download> item: "/gnu/store/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix" uri: "https://ci.guix.gnu.org/nar/zstd/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix" size: 313201 start: #<time type: time-monotonic nanosecond: 652962000 second: 1641417239> end: #<time type: time-monotonic nanosecond: 653772000 second: 1641417239> transferred: 313201> #<<download> item: "/gnu/store/3d9cyp999jsxamz018hiz20av600syrf-guix-extra" uri: "https://ci.guix.gnu.org/nar/zstd/3d9cyp999jsxamz018hiz20av600syrf-guix-extra" size: 3035344 start: #<time type: time-monotonic nanosecond: 217310000 second: 1641417238> end: #<time type: time-monotonic nanosecond: 373795000 second: 1641417239> transferred: 3035344> #<<download> item: "/gnu/store/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual" uri: "https://ci.guix.gnu.org/nar/lzip/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual" size: 7944251 start: #<time type: time-monotonic nanosecond: 149866000 second: 1641417236> end: #<time type: time-monotonic nanosecond: 895408000 second: 1641417237> transferred: 7944251>)>)
   179:23  2 (update-build #<<build-status> building: () downloading: () builds-completed: () downloads-completed: (#<<download> item: "/gnu/store/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix" uri: "https://ci.guix.gnu.org/nar/zstd/zxb0iiva4878ajkh1xq82mmm2csbwqk2-guix-locale-guix" size: 313201 start: #<time type: time-monotonic nanosecond: 652962000 second: 1641417239> end: #<time type: time-monotonic nanosecond: 653772000 second: 1641417239> transferred: 313201> #<<download> item: "/gnu/store/3d9cyp999jsxamz018hiz20av600syrf-guix-extra" uri: "https://ci.guix.gnu.org/nar/zstd/3d9cyp999jsxamz018hiz20av600syrf-guix-extra" size: 3035344 start: #<time type: time-monotonic nanosecond: 217310000 second: 1641417238> end: #<time type: time-monotonic nanosecond: 373795000 second: 1641417239> transferred: 3035344> #<<download> item: "/gnu/store/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual" uri: "https://ci.guix.gnu.org/nar/lzip/4y4qxga7yflgkdh0qgfj034q3das0ir4-guix-manual" size: 7944251 start: #<time type: time-monotonic nanosecond: 149866000 second: 1641417236> end: #<time type: time-monotonic nanosecond: 895408000 second: 1641417237> transferred: 7944251>)> _ _)
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

[signature.asc (application/pgp-signature, inline)]
Information forwarded to bug-guix <at> gnu.org:
bug#53027; Package guix. (Wed, 05 Jan 2022 21:30:02 GMT) Full text and rfc822 format available.

Message #11 received at 53027 <at> debbugs.gnu.org (full text, mbox):

From: Maxime Devos <maximedevos <at> telenet.be>
To: Oskar Berndal <o.berndal <at> gmail.com>, 53027 <at> debbugs.gnu.org
Subject: Re: bug#53027: Backtrace with `guix pull --debug=2`
Date: Wed, 05 Jan 2022 21:29:35 +0000

[Message part 1 (text/plain, inline)]
Oskar Berndal schreef op wo 05-01-2022 om 16:19 [+0100]:
>    179:23  2 (update-build #<<build-status> building: () downloadin…> …)
> 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

Seems like the issue is that 'find-build' returns #false
and 'update' doesn't expect that. So apparently a progress spinner
appears for a build that (guix status) doesn't know about?
I don't know how that can happen.

It's easy to address though (do nothing when no build was found),
but possibly that would only paper over the real issue.

Greetings,
Maxime.

[signature.asc (application/pgp-signature, inline)]
Information forwarded to bug-guix <at> gnu.org:
bug#53027; Package guix. (Wed, 05 Jan 2022 21:47:02 GMT) Full text and rfc822 format available.

Message #14 received at 53027 <at> debbugs.gnu.org (full text, mbox):

From: Oskar Berndal <o.berndal <at> gmail.com>
To: Maxime Devos <maximedevos <at> telenet.be>
Cc: 53027 <at> debbugs.gnu.org
Subject: Re: bug#53027: Backtrace with `guix pull --debug=2`
Date: Wed, 5 Jan 2022 22:46:05 +0100

Wow, you were able to reproduce. I feared that this would just be lost
to the sands, but apparently not. Nicely done!!! :-D
Information forwarded to bug-guix <at> gnu.org:
bug#53027; Package guix. (Thu, 06 Jan 2022 16:45:02 GMT) Full text and rfc822 format available.

Message #17 received at 53027 <at> debbugs.gnu.org (full text, mbox):

From: Ludovic Courtès <ludo <at> gnu.org>
To: Maxime Devos <maximedevos <at> telenet.be>
Cc: 53027 <at> debbugs.gnu.org, Oskar Berndal <o.berndal <at> gmail.com>
Subject: Re: bug#53027: Backtrace with `guix pull --debug=2`
Date: Thu, 06 Jan 2022 17:44:31 +0100

Hi,

Maxime Devos <maximedevos <at> telenet.be> skribis:

> Oskar Berndal schreef op wo 05-01-2022 om 16:19 [+0100]:
>>    179:23  2 (update-build #<<build-status> building: () downloadin…> …)
>> 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
>
> Seems like the issue is that 'find-build' returns #false
> and 'update' doesn't expect that. So apparently a progress spinner
> appears for a build that (guix status) doesn't know about?
> I don't know how that can happen.
>
> It's easy to address though (do nothing when no build was found),
> but possibly that would only paper over the real issue.

Yeah.  This looks like <https://issues.guix.gnu.org/43518>.

Ludo’.
bug closed, send any further explanations to 53027 <at> debbugs.gnu.org and Oskar Berndal <o.berndal <at> gmail.com> Request was from Andreas Enge <andreas <at> enge.fr> to control <at> debbugs.gnu.org. (Mon, 07 Jul 2025 10:57:05 GMT) Full text and rfc822 format available.
bug archived. Request was from Debbugs Internal Request <help-debbugs <at> gnu.org> to internal_control <at> debbugs.gnu.org. (Mon, 04 Aug 2025 11:24:17 GMT) Full text and rfc822 format available.
This bug report was last modified 10 days ago.

Previous Next

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