"View build log" - but it's empty (at first)!

Chris Marusich wrote on 16 Jul 2019 08:41

Recipients:(address . bug-guix@gnu.org)

Message-ID:87blxu1ny5.fsf@gmail.com

Hi,
Sometimes, I see an error like the following (it is not specific toant-bootstrap):

Toggle snippet (12 lines)$ guix package -m ~/profile-packages.scm ...building /gnu/store/76apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv...| 'build' phasebuilder for `/gnu/store/76apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv' failed with exit code 1build of /gnu/store/76apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv failedView build log at '/var/log/guix/drvs/76/apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv.bz2'.cannot build derivation `/gnu/store/3l11fmnyc4k1dcwlijk4vdsq70jc4lqs-icedtea-3.7.0.drv': 1 dependencies couldn't be builtkilling process 20600cannot build derivation `/gnu/store/b071r8bm2n01mp50g6r5ghxl0m09akdx-profile.drv': 1 dependencies couldn't be builtguix package: error: build of `/gnu/store/b071r8bm2n01mp50g6r5ghxl0m09akdx-profile.drv' failed

But when I try to view the log file very quickly after getting thesemessages (within about 1 minute or so), I get:

Toggle snippet (4 lines)

$ file /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2/var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2: empty

However, when I come back a few minutes later, mysteriously the file hasbeen populated:

Toggle snippet (4 lines)

$ file /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2 /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2: bzip2 compressed data, block size = 900k

Indeed, I was originally going to report that the file was empty, butwhile I was writing this email, it filled up!  Is this behaviorexpected?  I anticipated that the file would be finalized (empty or not)before the helpful message "View built log at ..." was printed, but itseems like that message is proleptic, which makes it a little confusing.
-- Chris

Chris Marusich wrote on 16 Jul 2019 08:47

Recipients:(address . bug-guix@gnu.org)

Message-ID:877e8i1nnm.fsf@gmail.com

Chris Marusich <cmmarusich@gmail.com> writes:

Toggle quote (34 lines)> Sometimes, I see an error like the following (it is not specific to> ant-bootstrap):>> $ guix package -m ~/profile-packages.scm > ...> building /gnu/store/76apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv...> | 'build' phasebuilder for `/gnu/store/76apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv' failed with exit code 1> build of /gnu/store/76apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv failed> View build log at '/var/log/guix/drvs/76/apf0hpcdabpjy0839nhkwgfrz3m8z5-ant-bootstrap-1.8.4.drv.bz2'.> cannot build derivation `/gnu/store/3l11fmnyc4k1dcwlijk4vdsq70jc4lqs-icedtea-3.7.0.drv': 1 dependencies couldn't be built> killing process 20600> cannot build derivation `/gnu/store/b071r8bm2n01mp50g6r5ghxl0m09akdx-profile.drv': 1 dependencies couldn't be built> guix package: error: build of `/gnu/store/b071r8bm2n01mp50g6r5ghxl0m09akdx-profile.drv' failed>>> But when I try to view the log file very quickly after getting these> messages (within about 1 minute or so), I get:>> $ file  /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2> /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2: empty>>> However, when I come back a few minutes later, mysteriously the file has> been populated:>> $ file  /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2 > /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2: bzip2 compressed data, block size = 900k>> Indeed, I was originally going to report that the file was empty, but> while I was writing this email, it filled up!  Is this behavior> expected?  I anticipated that the file would be finalized (empty or not)> before the helpful message "View built log at ..." was printed, but it> seems like that message is proleptic, which makes it a little confusing.

Whoops, I got my wires crossed. Those build logs are forevolution-data-server, not ant-bootstrap. However, the same problemholds for evolution-data-server - I built it, it failed, it said I couldfind the logs at/var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2,it was empty at first, but then it became full.
In fact, it is only partially full, since the file ends unexpectedly:

Toggle snippet (15 lines)

$ bzcat /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2 > /dev/null
bzcat: Compressed file ends unexpectedly; perhaps it is corrupted? *Possible* reason follows.bzcat: Success Input file = /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2, output file = (stdout)
It is possible that the compressed file(s) have become corrupted.You can use the -tvv option to test integrity of such files.
You can use the `bzip2recover' program to attempt to recoverdata from undamaged sections of corrupted files.

Sorry for the initial confusion, but I believe this is still a potentialissue, and I wonder if others have observed similar behavior.
-- Chris

Ludovic Courtès wrote on 17 Jul 2019 21:57

Re: bug#36684: "View build log" - but it's empty (at first)!

Recipients:(name . Chris Marusich)(address . cmmarusich@gmail.com)(address . 36684@debbugs.gnu.org)

Message-ID:877e8ga0ye.fsf@gnu.org

Hi Chris,
Chris Marusich <cmmarusich@gmail.com> skribis:

Toggle quote (7 lines)

> Whoops, I got my wires crossed. Those build logs are for> evolution-data-server, not ant-bootstrap. However, the same problem> holds for evolution-data-server - I built it, it failed, it said I could> find the logs at> /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2,> it was empty at first, but then it became full.

I guess there’s a small window during which the bzip file handle hasn’tbeen closed, and so you could see an empty file. However, that windowshould be extremely small: as soon as the client disconnects or thebuild terminates, the daemon closes the log.

Toggle quote (15 lines)> In fact, it is only partially full, since the file ends unexpectedly:>> $ bzcat /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2 > /dev/null>> bzcat: Compressed file ends unexpectedly;>         perhaps it is corrupted?  *Possible* reason follows.> bzcat: Success>         Input file = /var/log/guix/drvs/7f/n896phqyv9r636yhjipa9k4qm7g9ni-evolution-data-server-3.30.2.drv.bz2, output file = (stdout)>> It is possible that the compressed file(s) have become corrupted.> You can use the -tvv option to test integrity of such files.>> You can use the `bzip2recover' program to attempt to recover> data from undamaged sections of corrupted files.

So this particular log file remained truncated?
I’ve never experienced it. Is there a scenario that allows you toreproduce it?
Thanks,Ludo’.

Ludovic Courtès wrote on 24 Aug 2019 15:20

control message for bug #36684

Recipients:(address . control@debbugs.gnu.org)

Message-ID:87k1b2ya1t.fsf@gnu.org

tags 36684 + unreproduciblequit

Chris Marusich wrote on 17 Nov 2019 00:32

Re: bug#36684: "View build log" - but it's empty (at first)!

Recipients:(name . Ludovic Courtès)(address . ludo@gnu.org)

Message-ID:87zhgv5re5.fsf@garuda.local.i-did-not-set--mail-host-address--so-tickle-me

Ludovic Courtès <ludo@gnu.org> writes:

Toggle quote (3 lines)

> I’ve never experienced it. Is there a scenario that allows you to> reproduce it?

I have not been able to reproduce this issue; maybe I was simplyconfused when I opened the bug report. Let's close this for now.
-- Chris

Your comment

This issue is archived.

To comment on this conversation send email to 36684@debbugs.gnu.org

is:open	open issues
is:done	closed issues
submitter:<who>	search issue submitter
author:<who>	search by message author
date:yesterday..now	search by issue date
mdate:3m..2d	search by message date