Diff of the two buildlogs: -- --- b1/build.log 2024-06-05 08:09:40.961069678 +0000 +++ b2/build.log 2024-06-05 08:11:21.584791568 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue Jun 4 20:05:40 -12 2024 -I: pbuilder-time-stamp: 1717574740 +I: Current time: Wed Jul 9 04:32:43 +14 2025 +I: pbuilder-time-stamp: 1751985163 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-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/1701685/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/32681/tmp/hooks/D01_modify_environment starting +debug: Running on ionos15-amd64. +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 Jul 8 14:32 /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/32681/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/32681/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='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=20 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='amd64' + 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]="x86_64-pc-linux-gnu") + 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=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=42 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='d05a9a614e8c4cc5bb3864295e0eac3f' - 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='1701685' - PS1='# ' - PS2='> ' + INVOCATION_ID=de4c42d76754423081c726f784c12a34 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + 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=32681 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.NPMrpAzC/pbuilderrc_9SaB --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.NPMrpAzC/b1 --logfile b1/build.log golang-github-cznic-ql_1.0.6-1.1.dsc' - SUDO_GID='110' - SUDO_UID='105' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://46.16.76.132:3128' + 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.NPMrpAzC/pbuilderrc_8TBQ --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.NPMrpAzC/b2 --logfile b2/build.log golang-github-cznic-ql_1.0.6-1.1.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://213.165.73.152:3128 I: uname -a - Linux ionos1-amd64 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.7.12+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.7.12-1~bpo12+1 (2024-05-06) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Jun 5 07:42 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1701685/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jul 7 14:05 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/32681/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -170,7 +202,7 @@ Get: 42 http://deb.debian.org/debian unstable/main amd64 golang-github-golang-snappy-dev all 0.0.2-3 [53.9 kB] Get: 43 http://deb.debian.org/debian unstable/main amd64 golang-github-cznic-zappy-dev all 0.0~git20160305.0.4f5e6ef-3 [11.1 kB] Get: 44 http://deb.debian.org/debian unstable/main amd64 golang-github-cznic-lldb-dev all 1.0.1-1.1 [169 kB] -Fetched 63.1 MB in 7s (9653 kB/s) +Fetched 63.1 MB in 3s (25.1 MB/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 ... 19705 files and directories currently installed.) @@ -366,7 +398,11 @@ fakeroot is already the newest version (1.34-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/32681/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/32681/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 @@ -691,7 +727,7 @@ [2 b 2014-01-12 17:27:00 +0000] [1 hello 2014-01-12 17:28:00 +0000] ---- ---- FAIL: TestMemStorage (0.90s) +--- FAIL: TestMemStorage (0.41s) === RUN TestFileStorage storage_test.go:250: FAIL: 427 unknown time zone CET storage_test.go:250: FAIL: 429 unknown time zone CET @@ -831,7 +867,7 @@ [2 b 2014-01-12 17:27:00 +0000] [1 hello 2014-01-12 17:28:00 +0000] ---- ---- FAIL: TestFileStorage (3.41s) +--- FAIL: TestFileStorage (2.40s) === RUN TestOSFileStorage storage_test.go:250: FAIL: 427 unknown time zone CET storage_test.go:250: FAIL: 429 unknown time zone CET @@ -971,17 +1007,17 @@ [2 b 2014-01-12 17:27:00 +0000] [1 hello 2014-01-12 17:28:00 +0000] ---- ---- FAIL: TestOSFileStorage (3.21s) +--- FAIL: TestOSFileStorage (1.68s) === RUN TestString ---- PASS: TestString (0.10s) +--- PASS: TestString (0.05s) === RUN TestReopen ---- PASS: TestReopen (0.09s) +--- PASS: TestReopen (0.03s) === RUN TestLastInsertID --- PASS: TestLastInsertID (0.00s) === RUN TestRowsAffected --- PASS: TestRowsAffected (0.00s) === RUN TestIndices ---- PASS: TestIndices (0.65s) +--- PASS: TestIndices (0.21s) === RUN TestIndex all_test.go:1702: id 1 <- true all_test.go:1702: id 2 <- true @@ -992,28 +1028,40 @@ all_test.go:1702: id 9 <- true all_test.go:1718: n: 7 ---- + "__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] + record @16: [14 6 5 TableName] + record @14: [12 4 3 id()] + record @12: [0 2 1 Index2_ID] + index __xIndex2_Expr_Index2_ID on Index2_ID + [1]: 12 + [3]: 14 + [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] + 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 + [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] @@ -1038,18 +1086,6 @@ [__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] - record @16: [14 6 5 TableName] - record @14: [12 4 3 id()] - record @12: [0 2 1 Index2_ID] - index __xIndex2_Expr_Index2_ID on Index2_ID - [1]: 12 - [3]: 14 - [5]: 16 - [7]: 18 - [9]: 20 all_test.go:1731: [11 true] all_test.go:1731: [12 true] all_test.go:1731: [14 true] @@ -1061,7 +1097,7 @@ === RUN TestIssue35 --- PASS: TestIssue35 (0.00s) === RUN TestIssue28 ---- PASS: TestIssue28 (0.03s) +--- PASS: TestIssue28 (0.02s) === RUN TestIssue50 all_test.go:2117: Opening db. all_test.go:2162: md5 of 1.xyz: f0f9b4a8d65e0f7f6a824bffd8182e5c @@ -1070,10 +1106,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-06-05 08:09:27.46575556 +0000 UTC [] 0 2 3 0 0 0.3} {12 Job-0815 2024-06-05 08:09:27.461414958 +0000 UTC [] 0 2 3 0 0 0.3} {11 Job-0815 2024-06-05 08:09:27.458410416 +0000 UTC [] 0 2 3 0 0 0.3}] ---- PASS: TestIssue50 (0.31s) + all_test.go:2206: Done: [{13 Job-0815 2025-07-08 14:34:15.611623581 +0000 UTC [] 0 2 3 0 0 0.3} {12 Job-0815 2025-07-08 14:34:15.609999742 +0000 UTC [] 0 2 3 0 0 0.3} {11 Job-0815 2025-07-08 14:34:15.608317413 +0000 UTC [] 0 2 3 0 0 0.3}] +--- PASS: TestIssue50 (0.04s) === RUN TestIssue56 ---- PASS: TestIssue56 (0.03s) +--- PASS: TestIssue56 (0.02s) === RUN TestRecordSetRows all_test.go:2335: 0 all_test.go:2335: 1 @@ -1112,20 +1148,20 @@ all_test.go:2388: 6 --- PASS: TestRecordFirst (0.00s) === RUN TestIssue63 - all_test.go:2442: invalid operation: 2024-06-05 08:09:27.523816783 +0000 UTC m=+8.752651845 > 0 (mismatched types time.Time and int64) + all_test.go:2442: invalid operation: 2025-07-08 14:34:15.646457806 +0000 UTC m=+4.872913993 > 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.03s) +--- PASS: TestIssue66File (0.01s) === 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.03s) +--- PASS: TestIssue66FileDriver (0.01s) === RUN TestIssue73 all_test.go:2621: Call to db.Begin() all_test.go:2626: Call to tx.QueryRow() @@ -1167,7 +1203,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.02s) +--- PASS: TestIssue73 (0.01s) === RUN TestInPredicateBug --- PASS: TestInPredicateBug (0.00s) === RUN TestIssue108 @@ -1311,14 +1347,14 @@ === RUN ExampleUnmarshal --- PASS: ExampleUnmarshal (0.00s) FAIL -FAIL github.com/cznic/ql 8.859s +FAIL github.com/cznic/ql 4.927s ? github.com/cznic/ql/design [no test files] === RUN Example_testFile ---- PASS: Example_testFile (0.04s) +--- PASS: Example_testFile (0.02s) === RUN Example_testMem --- PASS: Example_testMem (0.00s) PASS -ok github.com/cznic/ql/driver 0.050s +ok github.com/cznic/ql/driver 0.024s ? github.com/cznic/ql/ql [no test files] === RUN TestLock lock_test.go:79: test lock, portable false @@ -1345,7 +1381,7 @@ === RUN TestLockInChild --- PASS: TestLockInChild (0.00s) PASS -ok github.com/cznic/ql/vendored/github.com/camlistore/go4/lock 0.025s +ok github.com/cznic/ql/vendored/github.com/camlistore/go4/lock 0.013s 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) @@ -1398,12 +1434,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/32681/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/32681/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/1701685 and its subdirectories -I: Current time: Tue Jun 4 20:09:40 -12 2024 -I: pbuilder-time-stamp: 1717574980 +I: removing directory /srv/workspace/pbuilder/32681 and its subdirectories +I: Current time: Wed Jul 9 04:34:20 +14 2025 +I: pbuilder-time-stamp: 1751985260