Diff of the two buildlogs: -- --- b1/build.log 2024-04-29 10:00:58.496114136 +0000 +++ b2/build.log 2024-04-29 10:15:38.790582892 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun Apr 28 21:52:57 -12 2024 -I: pbuilder-time-stamp: 1714384377 +I: Current time: Tue Apr 30 00:01:11 +14 2024 +I: pbuilder-time-stamp: 1714384871 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -27,52 +27,84 @@ dpkg-source: info: applying testdata.patch I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/1814/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/D01_modify_environment starting +debug: Running on cbxi4b. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Apr 29 10:02 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='4cf3ef24f52141c7ab90546c0656196a' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='1814' - PS1='# ' - PS2='> ' + INVOCATION_ID=db47dfa33cad4adcbd4960b3eeba4a77 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=12866 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.lch1YnKv/pbuilderrc_AyJr --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.lch1YnKv/b1 --logfile b1/build.log golang-github-cznic-ql_1.0.6-1.1.dsc' - SUDO_GID='110' - SUDO_UID='103' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.lch1YnKv/pbuilderrc_YHxJ --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.lch1YnKv/b2 --logfile b2/build.log golang-github-cznic-ql_1.0.6-1.1.dsc' + SUDO_GID=116 + SUDO_UID=112 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt64z 6.1.0-20-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-armmp #1 SMP Debian 6.1.85-1 (2024-04-11) armv7l GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 26 11:23 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1814/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 26 11:26 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -171,7 +203,7 @@ Get: 43 http://deb.debian.org/debian trixie/main armhf golang-github-golang-snappy-dev all 0.0.2-3 [53.9 kB] Get: 44 http://deb.debian.org/debian trixie/main armhf golang-github-cznic-zappy-dev all 0.0~git20160305.0.4f5e6ef-3 [11.1 kB] Get: 45 http://deb.debian.org/debian trixie/main armhf golang-github-cznic-lldb-dev all 1.0.1-1.1 [169 kB] -Fetched 59.1 MB in 1s (56.4 MB/s) +Fetched 59.1 MB in 8s (7274 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19470 files and directories currently installed.) @@ -371,7 +403,11 @@ fakeroot is already the newest version (1.33-1). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/reproducible-path/golang-github-cznic-ql-1.0.6/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../golang-github-cznic-ql_1.0.6-1.1_source.changes +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-cznic-ql-1.0.6/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../golang-github-cznic-ql_1.0.6-1.1_source.changes dpkg-buildpackage: info: source package golang-github-cznic-ql dpkg-buildpackage: info: source version 1.0.6-1.1 dpkg-buildpackage: info: source distribution unstable @@ -694,7 +730,7 @@ [2 b 2014-01-12 17:27:00 +0000] [1 hello 2014-01-12 17:28:00 +0000] ---- ---- FAIL: TestMemStorage (2.28s) +--- FAIL: TestMemStorage (4.72s) === RUN TestFileStorage storage_test.go:250: FAIL: 427 unknown time zone CET storage_test.go:250: FAIL: 429 unknown time zone CET @@ -834,7 +870,7 @@ [2 b 2014-01-12 17:27:00 +0000] [1 hello 2014-01-12 17:28:00 +0000] ---- ---- FAIL: TestFileStorage (9.58s) +--- FAIL: TestFileStorage (18.88s) === RUN TestOSFileStorage storage_test.go:250: FAIL: 427 unknown time zone CET storage_test.go:250: FAIL: 429 unknown time zone CET @@ -974,17 +1010,17 @@ [2 b 2014-01-12 17:27:00 +0000] [1 hello 2014-01-12 17:28:00 +0000] ---- ---- FAIL: TestOSFileStorage (8.81s) +--- FAIL: TestOSFileStorage (18.96s) === RUN TestString ---- PASS: TestString (0.19s) +--- PASS: TestString (0.63s) === RUN TestReopen ---- PASS: TestReopen (0.07s) +--- PASS: TestReopen (0.05s) === RUN TestLastInsertID --- PASS: TestLastInsertID (0.00s) === RUN TestRowsAffected --- PASS: TestRowsAffected (0.00s) === RUN TestIndices ---- PASS: TestIndices (0.76s) +--- PASS: TestIndices (0.68s) === RUN TestIndex all_test.go:1702: id 1 <- true all_test.go:1702: id 2 <- true @@ -995,30 +1031,52 @@ all_test.go:1702: id 9 <- true all_test.go:1718: n: 7 ---- + "t": hhead 2, head 30, scols0 "bb", scols "bb" + record @30: [29 20 false] + record @29: [28 19 true] + record @28: [27 18 true] + record @27: [26 17 false] + record @26: [25 16 true] + record @25: [24 15 true] + record @24: [23 14 true] + record @23: [22 13 false] + record @22: [21 12 true] + record @21: [0 11 true] + index x on b + [false]: 23 + [false]: 27 + [false]: 30 + [true]: 21 + [true]: 22 + [true]: 24 + [true]: 25 + [true]: 26 + [true]: 28 + [true]: 29 "__Index2": hhead 5, head 19, scols0 "sTableName|sIndexName|bIsUnique|bIsSimple|lRoot", scols "sTableName|sIndexName|bIsUnique|bIsSimple|lRoot" - record @19: [17 9 t x false true -1] - record @17: [15 7 __Index2 __xIndex2_IndexName true true -1] - record @15: [13 5 __Index2 __xIndex2_TableName false true -1] - record @13: [11 3 __Index2 __xIndex2_ID false true -1] - record @11: [0 1 __Index2_Expr __xIndex2_Expr_Index2_ID false true -1] + record @19: [17 9 t x false true -1] + record @17: [15 7 __Index2 __xIndex2_IndexName true true -1] + record @15: [13 5 __Index2 __xIndex2_TableName false true -1] + record @13: [11 3 __Index2 __xIndex2_ID false true -1] + record @11: [0 1 __Index2_Expr __xIndex2_Expr_Index2_ID false true -1] index __xIndex2_ID on id() - [1]: 11 - [3]: 13 - [5]: 15 - [7]: 17 - [9]: 19 + [1]: 11 + [3]: 13 + [5]: 15 + [7]: 17 + [9]: 19 index __xIndex2_TableName on TableName - [__Index2]: 13 - [__Index2]: 15 - [__Index2]: 17 - [__Index2_Expr]: 11 - [t]: 19 + [__Index2]: 13 + [__Index2]: 15 + [__Index2]: 17 + [__Index2_Expr]: 11 + [t]: 19 index __xIndex2_IndexName on IndexName - [__xIndex2_Expr_Index2_ID]: 11 - [__xIndex2_ID]: 13 - [__xIndex2_IndexName]: 17 - [__xIndex2_TableName]: 15 - [x]: 19 + [__xIndex2_Expr_Index2_ID]: 11 + [__xIndex2_ID]: 13 + [__xIndex2_IndexName]: 17 + [__xIndex2_TableName]: 15 + [x]: 19 "__Index2_Expr": hhead 7, head 20, scols0 "lIndex2_ID|sExpr", scols "lIndex2_ID|sExpr" record @20: [18 10 9 b] record @18: [16 8 7 IndexName] @@ -1031,28 +1089,6 @@ [5]: 16 [7]: 18 [9]: 20 - "t": hhead 2, head 30, scols0 "bb", scols "bb" - record @30: [29 20 false] - record @29: [28 19 true] - record @28: [27 18 true] - record @27: [26 17 false] - record @26: [25 16 true] - record @25: [24 15 true] - record @24: [23 14 true] - record @23: [22 13 false] - record @22: [21 12 true] - record @21: [0 11 true] - index x on b - [false]: 23 - [false]: 27 - [false]: 30 - [true]: 21 - [true]: 22 - [true]: 24 - [true]: 25 - [true]: 26 - [true]: 28 - [true]: 29 all_test.go:1731: [11 true] all_test.go:1731: [12 true] all_test.go:1731: [14 true] @@ -1060,11 +1096,11 @@ all_test.go:1731: [16 true] all_test.go:1731: [18 true] all_test.go:1731: [19 true] ---- PASS: TestIndex (0.00s) +--- PASS: TestIndex (0.01s) === RUN TestIssue35 --- PASS: TestIssue35 (0.00s) === RUN TestIssue28 ---- PASS: TestIssue28 (0.09s) +--- PASS: TestIssue28 (0.03s) === RUN TestIssue50 all_test.go:2117: Opening db. all_test.go:2162: md5 of 1.xyz: f0f9b4a8d65e0f7f6a824bffd8182e5c @@ -1073,10 +1109,10 @@ all_test.go:2198: md5 of 13: 3969b3de3ea10b52b039e446d5fe0562 all_test.go:2198: md5 of 12: 17c1d2f49854c7d24903f43b38552862 all_test.go:2198: md5 of 11: f0f9b4a8d65e0f7f6a824bffd8182e5c - all_test.go:2206: Done: [{13 Job-0815 2024-04-29 10:00:25.825601861 +0000 UTC [] 0 2 3 0 0 0.3} {12 Job-0815 2024-04-29 10:00:25.808001113 +0000 UTC [] 0 2 3 0 0 0.3} {11 Job-0815 2024-04-29 10:00:25.799940972 +0000 UTC [] 0 2 3 0 0 0.3}] ---- PASS: TestIssue50 (0.16s) + all_test.go:2206: Done: [{13 Job-0815 2024-04-29 10:14:52.304980631 +0000 UTC [] 0 2 3 0 0 0.3} {12 Job-0815 2024-04-29 10:14:52.289047026 +0000 UTC [] 0 2 3 0 0 0.3} {11 Job-0815 2024-04-29 10:14:52.26840081 +0000 UTC [] 0 2 3 0 0 0.3}] +--- PASS: TestIssue50 (0.18s) === RUN TestIssue56 ---- PASS: TestIssue56 (0.07s) +--- PASS: TestIssue56 (0.04s) === RUN TestRecordSetRows all_test.go:2335: 0 all_test.go:2335: 1 @@ -1104,7 +1140,7 @@ all_test.go:2335: 23 all_test.go:2335: 24 all_test.go:2335: 25 ---- PASS: TestRecordSetRows (0.00s) +--- PASS: TestRecordSetRows (0.01s) === RUN TestRecordFirst all_test.go:2388: 0 all_test.go:2388: 1 @@ -1115,20 +1151,20 @@ all_test.go:2388: 6 --- PASS: TestRecordFirst (0.00s) === RUN TestIssue63 - all_test.go:2442: invalid operation: 2024-04-29 10:00:25.950941618 +0000 UTC m=+22.050967602 > 0 (mismatched types time.Time and int64) + all_test.go:2442: invalid operation: 2024-04-29 10:14:52.449673151 +0000 UTC m=+44.236969096 > 0 (mismatched types time.Time and int64) --- PASS: TestIssue63 (0.00s) === RUN TestIssue66Mem all_test.go:2472: cannot insert into unique index: duplicate value(s): [1] --- PASS: TestIssue66Mem (0.00s) === RUN TestIssue66File all_test.go:2495: (file-018) cannot insert into unique index: duplicate value(s): [1] ---- PASS: TestIssue66File (0.06s) +--- PASS: TestIssue66File (0.04s) === RUN TestIssue66MemDriver all_test.go:2516: cannot insert into unique index: duplicate value(s): [1] --- PASS: TestIssue66MemDriver (0.00s) === RUN TestIssue66FileDriver all_test.go:2544: (file-018) cannot insert into unique index: duplicate value(s): [1] ---- PASS: TestIssue66FileDriver (0.05s) +--- PASS: TestIssue66FileDriver (0.04s) === RUN TestIssue73 all_test.go:2621: Call to db.Begin() all_test.go:2626: Call to tx.QueryRow() @@ -1170,7 +1206,7 @@ all_test.go:2626: Call to tx.QueryRow() all_test.go:2628: Call to tx.Commit() all_test.go:2634: name: ---- PASS: TestIssue73 (0.06s) +--- PASS: TestIssue73 (0.07s) === RUN TestInPredicateBug --- PASS: TestInPredicateBug (0.00s) === RUN TestIssue108 @@ -1277,7 +1313,7 @@ introspection_test.go:225: 1 cannot derive schema for () introspection_test.go:225: 11 BEGIN TRANSACTION; CREATE TABLE IF NOT EXISTS testSchema2 (); COMMIT; : 1:60 unexpected ')', expected table column definition or identifier introspection_test.go:225: 14 both index and uindex in QL struct tag ---- PASS: TestSchema (0.00s) +--- PASS: TestSchema (0.01s) === RUN TestMarshal introspection_test.go:541: cannot derive schema for type int (int) introspection_test.go:541: cannot derive schema for type *ql.u (int) @@ -1314,14 +1350,14 @@ === RUN ExampleUnmarshal --- PASS: ExampleUnmarshal (0.00s) FAIL -FAIL github.com/cznic/ql 22.299s +FAIL github.com/cznic/ql 44.468s ? github.com/cznic/ql/design [no test files] === RUN Example_testFile --- PASS: Example_testFile (0.04s) === RUN Example_testMem --- PASS: Example_testMem (0.00s) PASS -ok github.com/cznic/ql/driver 0.057s +ok github.com/cznic/ql/driver 0.078s ? github.com/cznic/ql/ql [no test files] === RUN TestLock lock_test.go:79: test lock, portable false @@ -1333,7 +1369,7 @@ lock_test.go:125: Locking in child... lock_test.go:130: Unlocking lock in parent lock_test.go:135: Trying lock again in child... ---- PASS: TestLock (0.08s) +--- PASS: TestLock (0.03s) === RUN TestLockPortable lock_test.go:79: test lock, portable true lock_test.go:92: First lock in child @@ -1344,11 +1380,11 @@ lock_test.go:125: Locking in child... lock_test.go:130: Unlocking lock in parent lock_test.go:135: Trying lock again in child... ---- PASS: TestLockPortable (0.06s) +--- PASS: TestLockPortable (0.03s) === RUN TestLockInChild --- PASS: TestLockInChild (0.00s) PASS -ok github.com/cznic/ql/vendored/github.com/camlistore/go4/lock 0.163s +ok github.com/cznic/ql/vendored/github.com/camlistore/go4/lock 0.083s FAIL dh_auto_test: error: cd _build && go test -vet=off -v -p 1 github.com/cznic/ql github.com/cznic/ql/design github.com/cznic/ql/driver github.com/cznic/ql/ql github.com/cznic/ql/vendored/github.com/camlistore/go4/lock returned exit code 1 make[1]: [debian/rules:10: override_dh_auto_test] Error 255 (ignored) @@ -1401,12 +1437,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/12866/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/1814 and its subdirectories -I: Current time: Sun Apr 28 22:00:54 -12 2024 -I: pbuilder-time-stamp: 1714384854 +I: removing directory /srv/workspace/pbuilder/12866 and its subdirectories +I: Current time: Tue Apr 30 00:15:34 +14 2024 +I: pbuilder-time-stamp: 1714385734