[Qa-jenkins-scm] Build failed in Jenkins: reproducible_builder_armhf_35 #10600
jenkins at jenkins.debian.net
jenkins at jenkins.debian.net
Thu Dec 15 12:21:34 UTC 2016
https://jenkins.debian.net/job/reproducible_builder_armhf_35/10600/------------------------------------------
[...truncated 1877 lines...]
dpkg-buildpackage: info: source package golang-github-hpcloud-tail
dpkg-buildpackage: info: source version 1.0.0+git20160415.b294095-3
dpkg-buildpackage: info: source distribution unstable
dpkg-buildpackage: info: source changed by Daniel Stender <stender at debian.org>
- dpkg-source --before-build golang-github-hpcloud-tail-1.0.0+git20160415.b294095
+ dpkg-source --before-build 2nd
dpkg-buildpackage: info: host architecture armhf
fakeroot debian/rules clean
dh clean --buildsystem=golang --with=golang
@@ -323,16 +337,16 @@
github.com/hpcloud/tail
github.com/hpcloud/tail/cmd/gotail
debian/rules override_dh_auto_test
-make[1]: Entering directory '/build/1st/golang-github-hpcloud-tail-1.0.0+git20160415.b294095'
+make[1]: Entering directory '/build/golang-github-hpcloud-tail-1.0.0+git20160415.b294095/2nd'
dh_auto_test
go test -v -p 1 github.com/hpcloud/tail github.com/hpcloud/tail/cmd/gotail github.com/hpcloud/tail/ratelimiter github.com/hpcloud/tail/util github.com/hpcloud/tail/watch
=== RUN TestMustExist
-2016/12/15 12:00:40 Waiting for /no/such/file to appear...
+2016/12/15 12:15:18 Waiting for /no/such/file to appear...
--- FAIL: TestMustExist (0.01s)
tail_test.go:48: MustExist:true on an existing file is violated
=== RUN TestStop
-2016/12/15 12:00:40 Waiting for _no_such_file to appear...
---- PASS: TestStop (0.01s)
+2016/12/15 12:15:18 Waiting for _no_such_file to appear...
+--- PASS: TestStop (0.02s)
=== RUN TestStopAtEOF
--- PASS: TestStopAtEOF (0.11s)
=== RUN TestMaxLineSizeFollow
@@ -340,54 +354,54 @@
=== RUN TestMaxLineSizeNoFollow
--- PASS: TestMaxLineSizeNoFollow (0.10s)
=== RUN TestOver4096ByteLine
---- PASS: TestOver4096ByteLine (0.10s)
+--- PASS: TestOver4096ByteLine (0.11s)
=== RUN TestOver4096ByteLineWithSetMaxLineSize
--- PASS: TestOver4096ByteLineWithSetMaxLineSize (0.11s)
=== RUN TestLocationFull
---- PASS: TestLocationFull (0.11s)
+--- PASS: TestLocationFull (0.10s)
=== RUN TestLocationFullDontFollow
--- PASS: TestLocationFullDontFollow (0.21s)
=== RUN TestLocationEnd
-2016/12/15 12:00:41 Seeked .test/location-end/test.txt - &{Offset:0 Whence:2}
+2016/12/15 12:15:19 Seeked .test/location-end/test.txt - &{Offset:0 Whence:2}
--- PASS: TestLocationEnd (0.21s)
=== RUN TestLocationMiddle
-2016/12/15 12:00:41 Seeked .test/location-middle/test.txt - &{Offset:-6 Whence:2}
+2016/12/15 12:15:19 Seeked .test/location-middle/test.txt - &{Offset:-6 Whence:2}
--- PASS: TestLocationMiddle (0.21s)
=== RUN TestReOpenInotify
---- PASS: TestReOpenInotify (0.64s)
+--- PASS: TestReOpenInotify (0.63s)
=== RUN TestReOpenPolling
-2016/12/15 12:00:42 Re-opening moved/deleted file .test/reopen-polling/test.txt ...
-2016/12/15 12:00:42 Waiting for .test/reopen-polling/test.txt to appear...
-2016/12/15 12:00:42 Successfully reopened .test/reopen-polling/test.txt
-2016/12/15 12:00:43 Re-opening moved/deleted file .test/reopen-polling/test.txt ...
-2016/12/15 12:00:43 Waiting for .test/reopen-polling/test.txt to appear...
-2016/12/15 12:00:43 Successfully reopened .test/reopen-polling/test.txt
-2016/12/15 12:00:43 Re-opening moved/deleted file .test/reopen-polling/test.txt ...
-2016/12/15 12:00:43 Waiting for .test/reopen-polling/test.txt to appear...
---- PASS: TestReOpenPolling (1.83s)
+2016/12/15 12:15:20 Re-opening moved/deleted file .test/reopen-polling/test.txt ...
+2016/12/15 12:15:20 Waiting for .test/reopen-polling/test.txt to appear...
+2016/12/15 12:15:20 Successfully reopened .test/reopen-polling/test.txt
+2016/12/15 12:15:21 Re-opening moved/deleted file .test/reopen-polling/test.txt ...
+2016/12/15 12:15:21 Waiting for .test/reopen-polling/test.txt to appear...
+2016/12/15 12:15:21 Successfully reopened .test/reopen-polling/test.txt
+2016/12/15 12:15:21 Re-opening moved/deleted file .test/reopen-polling/test.txt ...
+2016/12/15 12:15:21 Waiting for .test/reopen-polling/test.txt to appear...
+--- PASS: TestReOpenPolling (1.84s)
=== RUN TestReSeekInotify
-2016/12/15 12:00:44 Re-opening truncated file .test/reseek-inotify/test.txt ...
-2016/12/15 12:00:44 Successfully reopened truncated .test/reseek-inotify/test.txt
---- PASS: TestReSeekInotify (0.22s)
+2016/12/15 12:15:22 Re-opening truncated file .test/reseek-inotify/test.txt ...
+2016/12/15 12:15:22 Successfully reopened truncated .test/reseek-inotify/test.txt
+--- PASS: TestReSeekInotify (0.21s)
=== RUN TestReSeekPolling
-2016/12/15 12:00:44 Re-opening truncated file .test/reseek-polling/test.txt ...
-2016/12/15 12:00:44 Successfully reopened truncated .test/reseek-polling/test.txt
+2016/12/15 12:15:22 Re-opening truncated file .test/reseek-polling/test.txt ...
+2016/12/15 12:15:22 Successfully reopened truncated .test/reseek-polling/test.txt
--- PASS: TestReSeekPolling (0.21s)
=== RUN TestRateLimiting
-2016/12/15 12:00:44 Stopping tail as file no longer exists: .test/reseek-polling/test.txt
-2016/12/15 12:00:44 Leaky bucket full (.test/rate-limiting/test.txt); entering 1s cooloff period.
-2016/12/15 12:00:45 Leaky bucket full (.test/rate-limiting/test.txt); entering 1s cooloff period.
---- PASS: TestRateLimiting (1.32s)
+2016/12/15 12:15:22 Leaky bucket full (.test/rate-limiting/test.txt); entering 1s cooloff period.
+2016/12/15 12:15:22 Stopping tail as file no longer exists: .test/reseek-polling/test.txt
+2016/12/15 12:15:23 Leaky bucket full (.test/rate-limiting/test.txt); entering 1s cooloff period.
+--- PASS: TestRateLimiting (1.31s)
=== RUN TestTell
-2016/12/15 12:00:45 Seeked .test/tell-position/test.txt - &{Offset:0 Whence:0}
-2016/12/15 12:00:45 Seeked .test/tell-position/test.txt - &{Offset:12 Whence:0}
+2016/12/15 12:15:23 Seeked .test/tell-position/test.txt - &{Offset:0 Whence:0}
+2016/12/15 12:15:23 Seeked .test/tell-position/test.txt - &{Offset:12 Whence:0}
--- PASS: TestTell (0.00s)
=== RUN TestBlockUntilExists
-2016/12/15 12:00:45 Waiting for .test/block-until-file-exists/test.txt to appear...
---- PASS: TestBlockUntilExists (0.13s)
+2016/12/15 12:15:23 Waiting for .test/block-until-file-exists/test.txt to appear...
+--- PASS: TestBlockUntilExists (0.11s)
FAIL
exit status 1
-FAIL github.com/hpcloud/tail 5.709s
+FAIL github.com/hpcloud/tail 5.708s
? github.com/hpcloud/tail/cmd/gotail [no test files]
=== RUN TestPour
--- PASS: TestPour (0.00s)
@@ -396,23 +410,23 @@
=== RUN TestTimeToDrain
--- PASS: TestTimeToDrain (0.00s)
PASS
-ok github.com/hpcloud/tail/ratelimiter 0.045s
+ok github.com/hpcloud/tail/ratelimiter 0.035s
? github.com/hpcloud/tail/util [no test files]
? github.com/hpcloud/tail/watch [no test files]
dh_auto_test: go test -v -p 1 github.com/hpcloud/tail github.com/hpcloud/tail/cmd/gotail github.com/hpcloud/tail/ratelimiter github.com/hpcloud/tail/util github.com/hpcloud/tail/watch returned exit code 1
debian/rules:9: recipe for target 'override_dh_auto_test' failed
make[1]: [override_dh_auto_test] Error 1 (ignored)
rm -rf obj-*-linux-gnu/src/github.com/hpcloud/tail/.test
-make[1]: Leaving directory '/build/1st/golang-github-hpcloud-tail-1.0.0+git20160415.b294095'
+make[1]: Leaving directory '/build/golang-github-hpcloud-tail-1.0.0+git20160415.b294095/2nd'
fakeroot debian/rules binary
dh binary --buildsystem=golang --with=golang
dh_testroot -O--buildsystem=golang
dh_prep -O--buildsystem=golang
dh_auto_install -O--buildsystem=golang
- mkdir -p /build/1st/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/debian/tmp/usr
- cp -r bin /build/1st/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/debian/tmp/usr
- mkdir -p /build/1st/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/debian/tmp/usr/share/gocode/src/github.com/hpcloud/tail
- cp -r -T src/github.com/hpcloud/tail /build/1st/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/debian/tmp/usr/share/gocode/src/github.com/hpcloud/tail
+ mkdir -p /build/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/2nd/debian/tmp/usr
+ cp -r bin /build/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/2nd/debian/tmp/usr
+ mkdir -p /build/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/2nd/debian/tmp/usr/share/gocode/src/github.com/hpcloud/tail
+ cp -r -T src/github.com/hpcloud/tail /build/golang-github-hpcloud-tail-1.0.0\+git20160415.b294095/2nd/debian/tmp/usr/share/gocode/src/github.com/hpcloud/tail
dh_install -O--buildsystem=golang
dh_installdocs -O--buildsystem=golang
dh_installchangelogs -O--buildsystem=golang
@@ -435,21 +449,23 @@
dpkg-gencontrol: warning: File::FcntlLock not available; using flock which is not NFS-safe
dh_md5sums -O--buildsystem=golang
dh_builddeb -u-Zxz -O--buildsystem=golang
-dpkg-deb: building package 'golang-github-hpcloud-tail-dev' in '../golang-github-hpcloud-tail-dev_1.0.0+git20160415.b294095-3_all.deb'.
dpkg-deb: building package 'gotail' in '../gotail_1.0.0+git20160415.b294095-3_armhf.deb'.
+dpkg-deb: building package 'golang-github-hpcloud-tail-dev' in '../golang-github-hpcloud-tail-dev_1.0.0+git20160415.b294095-3_all.deb'.
dpkg-genbuildinfo --build=binary
dpkg-genbuildinfo: warning: File::FcntlLock not available; using flock which is not NFS-safe
dpkg-genchanges --build=binary >../golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3_armhf.changes
dpkg-genchanges: info: binary-only upload (no source code included)
- dpkg-source --after-build golang-github-hpcloud-tail-1.0.0+git20160415.b294095
+ dpkg-source --after-build 2nd
dpkg-buildpackage: info: binary-only upload (no source included)
I: copying local configuration
+I: user script /srv/workspace/pbuilder/19862/tmp/hooks/B01_cleanup starting
+I: user script /srv/workspace/pbuilder/19862/tmp/hooks/B01_cleanup finished
I: unmounting /dev/shm filesystem
I: unmounting dev/pts filesystem
I: unmounting run/shm filesystem
I: unmounting proc filesystem
I: unmounting sys filesystem
I: cleaning the build env
-I: removing directory /srv/workspace/pbuilder/31253 and its subdirectories
-I: Current time: Thu Dec 15 00:02:02 -12 2016
-I: pbuilder-time-stamp: 1481803322
+I: removing directory /srv/workspace/pbuilder/19862 and its subdirectories
+I: Current time: Fri Dec 16 02:17:08 +14 2016
+I: pbuilder-time-stamp: 1481804228
Compressing the 2nd log...
/var/lib/jenkins/userContent/reproducible/debian/logdiffs/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.diff: 76.2% -- replaced with /var/lib/jenkins/userContent/reproducible/debian/logdiffs/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.diff.gz
b2/build.log: 78.9%
+ echo 'Compressing the 1st log...'
Compressing the 1st log...
+ gzip -9cvn b1/build.log
b1/build.log: 79.2%
+ chmod 644 /var/lib/jenkins/userContent/reproducible/debian/logs/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.build1.log.gz
+ update_rbuildlog
+ chmod 644 /srv/reproducible-results/rbuild-debian-FWg257PD/tmp.juDgTcGaq2
+ mv /srv/reproducible-results/rbuild-debian-FWg257PD/tmp.juDgTcGaq2 /var/lib/jenkins/userContent/reproducible/debian/rbuild/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.rbuild.log
+ RBUILDLOG=/var/lib/jenkins/userContent/reproducible/debian/rbuild/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.rbuild.log
+ '[' 0 -eq 1 ']'
+ '[' 0 -eq 0 ']'
+ filter_changes_files
+ sed -i -e '/^ [a-f0-9]\{32,64\} .*\.buildinfo$/d' b1/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3_armhf.changes b2/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3_armhf.changes
+ call_diffoscope_on_changes_files
++ mktemp --tmpdir=/srv/reproducible-results/rbuild-debian-FWg257PD
+ local TMPLOG=/srv/reproducible-results/rbuild-debian-FWg257PD/tmp.HmsF1RFVOp
+ local TIMEOUT=120m
+ DBDSUITE=unstable
+ '[' unstable = experimental ']'
+ set -x
++ mktemp --tmpdir=/srv/reproducible-results/rbuild-debian-FWg257PD -d dbd-tmp-XXXXXXX
+ local TEMP=/srv/reproducible-results/rbuild-debian-FWg257PD/dbd-tmp-M2mbT6H
++ schroot --directory /srv/reproducible-results/rbuild-debian-FWg257PD -c source:jenkins-reproducible-unstable-diffoscope diffoscope -- --version
+ DIFFOSCOPE='diffoscope 63'
++ echo diffoscope 63
++ grep '^E: 15binfmt: update-binfmts: unable to open'
++ true
+ LOG_RESULT=
+ '[' '!' -z '' ']'
+ log_info 'diffoscope 63 will be used to compare the two builds:'
+ _log I: 'diffoscope 63 will be used to compare the two builds:'
+ local prefix=I:
+ shift 1
+ tee -a /var/lib/jenkins/userContent/reproducible/debian/rbuild/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.rbuild.log
++ date -u
+ echo -e 'Thu Dec 15 12:17:22 UTC 2016 I: diffoscope 63 will be used to compare the two builds:'
Thu Dec 15 12:17:22 UTC 2016 I: diffoscope 63 will be used to compare the two builds:
+ set +e
+ set -x
+ timeout 120m nice schroot --directory /srv/reproducible-results/rbuild-debian-FWg257PD -c source:jenkins-reproducible-unstable-diffoscope -- sh -c 'export TMPDIR=/srv/reproducible-results/rbuild-debian-FWg257PD/dbd-tmp-M2mbT6H ; diffoscope --html /srv/reproducible-results/rbuild-debian-FWg257PD/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.diffoscope.html --text /srv/reproducible-results/rbuild-debian-FWg257PD/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.diffoscope.txt /srv/reproducible-results/rbuild-debian-FWg257PD/b1/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3_armhf.changes /srv/reproducible-results/rbuild-debian-FWg257PD/b2/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3_armhf.changes'
+ RESULT=1
++ grep '^E: 15binfmt: update-binfmts: unable to open' /srv/reproducible-results/rbuild-debian-FWg257PD/tmp.HmsF1RFVOp
++ true
+ LOG_RESULT=
+ '[' '!' -z '' ']'
+ false
+ set +x
Thu Dec 15 12:17:32 UTC 2016 E: golang-github-hpcloud-tail failed to build reproducibly in unstable on armhf.
Error: database is locked
Error: database is locked
Error: database is locked
Starting cleanup.
/var/lib/jenkins/userContent/reproducible/debian/rbuild/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.rbuild.log: 75.9% -- replaced with /var/lib/jenkins/userContent/reproducible/debian/rbuild/unstable/armhf/golang-github-hpcloud-tail_1.0.0+git20160415.b294095-3.rbuild.log.gz
INFO: Starting at 2016-12-15 12:20:33.670174
CRITICAL: SQLite database locked, could not execute query:
CRITICAL: "SELECT r.status, r.version, r.build_date
CRITICAL: FROM results AS r JOIN sources AS s
CRITICAL: ON r.package_id=s.id WHERE s.name='golang-github-hpcloud-tail'
CRITICAL: AND s.architecture='amd64' AND s.suite='testing'"
CRITICAL: Exiting script.
INFO: Finished at 2016-12-15 12:21:33.777372, took: 0:01:00.107227
Warning: cannot update html pages for golang-github-hpcloud-tail
All cleanup done.
Thu 15 Dec 12:21:33 UTC 2016 - /srv/jenkins/bin/reproducible_build.sh stopped running as /tmp/jenkins-script-r2740eKW, which will now be removed.
Build step 'Execute shell' marked build as failure
More information about the Qa-jenkins-scm
mailing list