Diff of the two buildlogs: -- --- b1/build.log 2023-04-22 15:29:42.599297436 +0000 +++ b2/build.log 2023-04-22 15:32:02.381100658 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri May 24 09:50:27 -12 2024 -I: pbuilder-time-stamp: 1716587427 +I: Current time: Sun Apr 23 05:29:47 +14 2023 +I: pbuilder-time-stamp: 1682177387 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./pgcopydb_0.10.orig.tar.gz] I: copying [./pgcopydb_0.10-1.debian.tar.xz] I: Extracting source -gpgv: Signature made Fri Nov 4 03:19:36 2022 -12 +gpgv: Signature made Sat Nov 5 05:19:36 2022 +14 gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./pgcopydb_0.10-1.dsc: no acceptable signature found @@ -25,52 +25,84 @@ dpkg-source: info: unpacking pgcopydb_0.10-1.debian.tar.xz I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/19001/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/11513/tmp/hooks/D01_modify_environment starting +debug: Running on codethink12-arm64. +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 23 05:29 /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/11513/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/11513/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + 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]="15" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=8' - DISTRIBUTION='bookworm' - HOME='/var/lib/jenkins' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/var/lib/jenkins + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - 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='19001' - PS1='# ' - PS2='> ' + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-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=11513 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.24nH1Bdr/pbuilderrc_Wnsq --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.24nH1Bdr/b1 --logfile b1/build.log pgcopydb_0.10-1.dsc' - SUDO_GID='117' - SUDO_UID='110' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - USERNAME='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.16: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.24nH1Bdr/pbuilderrc_qzxD --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.24nH1Bdr/b2 --logfile b2/build.log --extrapackages usrmerge pgcopydb_0.10-1.dsc' + SUDO_GID=117 + SUDO_UID=110 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + USERNAME=root + _='I: set' + http_proxy=http://192.168.101.16:3128 I: uname -a - Linux codethink11-arm64 4.15.0-209-generic #220-Ubuntu SMP Tue Mar 21 19:52:44 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-209-generic #220-Ubuntu SMP Tue Mar 21 19:52:44 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 24 04:47 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/19001/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 22 00:25 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/11513/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -282,7 +314,7 @@ Get: 146 http://deb.debian.org/debian bookworm/main arm64 sphinx-common all 5.3.0-4 [653 kB] Get: 147 http://deb.debian.org/debian bookworm/main arm64 python3-sphinx all 5.3.0-4 [549 kB] Get: 148 http://deb.debian.org/debian bookworm/main arm64 zlib1g-dev arm64 1:1.2.13.dfsg-1 [913 kB] -Fetched 163 MB in 5s (32.3 MB/s) +Fetched 163 MB in 3s (50.9 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libjson-perl. (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 ... 19616 files and directories currently installed.) @@ -873,7 +905,7 @@ selecting dynamic shared memory implementation ... posix selecting default max_connections ... 100 selecting default shared_buffers ... 128MB -selecting default time zone ... /usr/share/zoneinfo/Etc/GMT+12 +selecting default time zone ... /usr/share/zoneinfo/Etc/GMT-14 creating configuration files ... ok running bootstrap script ... ok performing post-bootstrap initialization ... ok @@ -936,10 +968,15 @@ Reading package lists... Building dependency tree... Reading state information... +usrmerge is already the newest version (35). fakeroot is already the newest version (1.31-1.2). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/pgcopydb-0.10/ && 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 > ../pgcopydb_0.10-1_source.changes +I: user script /srv/workspace/pbuilder/11513/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/11513/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +I: Running cd /build/pgcopydb-0.10/ && 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 > ../pgcopydb_0.10-1_source.changes dpkg-buildpackage: info: source package pgcopydb dpkg-buildpackage: info: source version 0.10-1 dpkg-buildpackage: info: source distribution unstable @@ -1073,7 +1110,7 @@ make[1]: Leaving directory '/build/pgcopydb-0.10' debian/rules override_dh_auto_test make[1]: Entering directory '/build/pgcopydb-0.10' -PATH=/build/pgcopydb-0.10/src/bin/pgcopydb:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games debian/tests/copydb +PATH=/build/pgcopydb-0.10/src/bin/pgcopydb:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path debian/tests/copydb + WORKDIR=/build/pgcopydb-0.10/debian/home + pg_virtualenv Creating new PostgreSQL cluster 15/regress ... @@ -1084,51 +1121,51 @@ + rm -rf /build/pgcopydb-0.10/debian/home + HOME=/build/pgcopydb-0.10/debian/home + pgcopydb copy-db --source dbname=src --target dbname=dst --dir /build/pgcopydb-0.10/debian/home -09:52:28 27774 INFO Running pgcopydb version 0.10-1 from "/build/pgcopydb-0.10/src/bin/pgcopydb/pgcopydb" -09:52:28 27774 INFO [SOURCE] Copying database from "postgres://@:/src?" -09:52:28 27774 INFO [TARGET] Copying database into "postgres://@:/dst?" -09:52:28 27774 INFO Exported snapshot "00000003-00000010-1" from the source database -09:52:28 27779 INFO STEP 1: dump the source database schema (pre/post data) -09:52:28 27779 INFO /usr/lib/postgresql/15/bin/pg_dump -Fc --snapshot 00000003-00000010-1 --section pre-data --file /build/pgcopydb-0.10/debian/home/schema/pre.dump 'postgres://@:/src?' -09:52:29 27779 INFO /usr/lib/postgresql/15/bin/pg_dump -Fc --snapshot 00000003-00000010-1 --section post-data --file /build/pgcopydb-0.10/debian/home/schema/post.dump 'postgres://@:/src?' -09:52:29 27779 INFO STEP 2: fetch source database tables, indexes, and sequences -09:52:29 27779 INFO Fetched information for 1 extensions -09:52:29 27779 INFO Fetched information for 1 tables, with an estimated total of 18 quintillion tuples and 8192 B -09:52:29 27779 INFO Fetched information for 0 indexes -09:52:29 27779 INFO Fetching information for 0 sequences -09:52:29 27779 INFO STEP 3: restore the pre-data section to the target database -09:52:29 27779 INFO /usr/lib/postgresql/15/bin/pg_restore --dbname 'postgres://@:/dst?' --single-transaction --use-list /build/pgcopydb-0.10/debian/home/schema/pre.list /build/pgcopydb-0.10/debian/home/schema/pre.dump -09:52:29 27779 INFO STEP 4: copy data from source to target in 1 sub-processes -09:52:29 27779 INFO STEP 5: copy Large Objects (BLOBs) in 1 sub-process -09:52:29 27779 INFO STEP 6: starting 4 CREATE INDEX processes -09:52:29 27779 INFO STEP 7: constraints are built by the CREATE INDEX processes -09:52:29 27779 INFO STEP 8: starting 4 VACUUM processes -09:52:29 27779 INFO STEP 9: reset sequences values -09:52:29 27968 INFO Reset sequences values on the target database -09:52:29 27971 INFO COPY "public"."foo" -09:52:29 27958 INFO Copying large objects -09:52:29 27967 INFO VACUUM ANALYZE "public"."foo"; -09:52:29 27779 INFO COPY phase is done, now waiting for vacuum, index, blob, and sequences processes -09:52:29 27779 INFO STEP 10: restore the post-data section to the target database -09:52:29 27779 INFO /usr/lib/postgresql/15/bin/pg_restore --dbname 'postgres://@:/dst?' --single-transaction --use-list /build/pgcopydb-0.10/debian/home/schema/post.list /build/pgcopydb-0.10/debian/home/schema/post.dump +05:31:49 21010 INFO Running pgcopydb version 0.10-1 from "/build/pgcopydb-0.10/src/bin/pgcopydb/pgcopydb" +05:31:49 21010 INFO [SOURCE] Copying database from "postgres://@:/src?" +05:31:49 21010 INFO [TARGET] Copying database into "postgres://@:/dst?" +05:31:49 21010 INFO Exported snapshot "00000003-00000010-1" from the source database +05:31:49 21014 INFO STEP 1: dump the source database schema (pre/post data) +05:31:49 21014 INFO /usr/lib/postgresql/15/bin/pg_dump -Fc --snapshot 00000003-00000010-1 --section pre-data --file /build/pgcopydb-0.10/debian/home/schema/pre.dump 'postgres://@:/src?' +05:31:50 21014 INFO /usr/lib/postgresql/15/bin/pg_dump -Fc --snapshot 00000003-00000010-1 --section post-data --file /build/pgcopydb-0.10/debian/home/schema/post.dump 'postgres://@:/src?' +05:31:50 21014 INFO STEP 2: fetch source database tables, indexes, and sequences +05:31:50 21014 INFO Fetched information for 1 extensions +05:31:50 21014 INFO Fetched information for 1 tables, with an estimated total of 18 quintillion tuples and 8192 B +05:31:50 21014 INFO Fetched information for 0 indexes +05:31:50 21014 INFO Fetching information for 0 sequences +05:31:50 21014 INFO STEP 3: restore the pre-data section to the target database +05:31:50 21014 INFO /usr/lib/postgresql/15/bin/pg_restore --dbname 'postgres://@:/dst?' --single-transaction --use-list /build/pgcopydb-0.10/debian/home/schema/pre.list /build/pgcopydb-0.10/debian/home/schema/pre.dump +05:31:50 21014 INFO STEP 4: copy data from source to target in 1 sub-processes +05:31:50 21014 INFO STEP 5: copy Large Objects (BLOBs) in 1 sub-process +05:31:50 21014 INFO STEP 6: starting 4 CREATE INDEX processes +05:31:50 21014 INFO STEP 7: constraints are built by the CREATE INDEX processes +05:31:50 21014 INFO STEP 8: starting 4 VACUUM processes +05:31:50 21014 INFO STEP 9: reset sequences values +05:31:50 21035 INFO Reset sequences values on the target database +05:31:50 21037 INFO COPY "public"."foo" +05:31:50 21025 INFO Copying large objects +05:31:50 21034 INFO VACUUM ANALYZE "public"."foo"; +05:31:50 21014 INFO COPY phase is done, now waiting for vacuum, index, blob, and sequences processes +05:31:50 21014 INFO STEP 10: restore the post-data section to the target database +05:31:50 21014 INFO /usr/lib/postgresql/15/bin/pg_restore --dbname 'postgres://@:/dst?' --single-transaction --use-list /build/pgcopydb-0.10/debian/home/schema/post.list /build/pgcopydb-0.10/debian/home/schema/post.dump OID | Schema | Name | copy duration | indexes | create index duration ------+--------+------+---------------+---------+---------------------- -16386 | public | foo | 47ms | 0 | 0ms +16386 | public | foo | 39ms | 0 | 0ms Step Connection Duration Concurrency -------------------------------------------------- ---------- ---------- ------------ - Dump Schema source 493ms 1 - Catalog Queries (table ordering, filtering, etc) source 33ms 1 - Prepare Schema target 91ms 1 - COPY, INDEX, CONSTRAINTS, VACUUM (wall clock) both 320ms 1 + 5 - COPY (cumulative) both 47ms 1 - Large Objects (cumulative) both 155ms 1 + Dump Schema source 409ms 1 + Catalog Queries (table ordering, filtering, etc) source 26ms 1 + Prepare Schema target 80ms 1 + COPY, INDEX, CONSTRAINTS, VACUUM (wall clock) both 212ms 1 + 5 + COPY (cumulative) both 39ms 1 + Large Objects (cumulative) both 79ms 1 CREATE INDEX, CONSTRAINTS (cumulative) target 0ms 4 - Finalize Schema target 82ms 1 + Finalize Schema target 70ms 1 -------------------------------------------------- ---------- ---------- ------------ - Total Wall Clock Duration both 1s022 1 + 5 + Total Wall Clock Duration both 801ms 1 + 5 -------------------------------------------------- ---------- ---------- ------------ + pg_dump -t foo dst @@ -1155,7 +1192,7 @@ SET default_table_access_method = heap; -- --- Name: foo; Type: TABLE; Schema: public; Owner: pbuilder1 +-- Name: foo; Type: TABLE; Schema: public; Owner: pbuilder2 -- CREATE TABLE public.foo ( @@ -1163,10 +1200,10 @@ ); -ALTER TABLE public.foo OWNER TO pbuilder1; +ALTER TABLE public.foo OWNER TO pbuilder2; -- --- Data for Name: foo; Type: TABLE DATA; Schema: public; Owner: pbuilder1 +-- Data for Name: foo; Type: TABLE DATA; Schema: public; Owner: pbuilder2 -- COPY public.foo ("?column?") FROM stdin; @@ -1219,8 +1256,8 @@ dh_gencontrol dh_md5sums dh_builddeb -dpkg-deb: building package 'pgcopydb-dbgsym' in '../pgcopydb-dbgsym_0.10-1_arm64.deb'. dpkg-deb: building package 'pgcopydb' in '../pgcopydb_0.10-1_arm64.deb'. +dpkg-deb: building package 'pgcopydb-dbgsym' in '../pgcopydb-dbgsym_0.10-1_arm64.deb'. dpkg-genbuildinfo --build=binary -O../pgcopydb_0.10-1_arm64.buildinfo dpkg-genchanges --build=binary -O../pgcopydb_0.10-1_arm64.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -1228,12 +1265,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/11513/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/11513/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/19001 and its subdirectories -I: Current time: Fri May 24 09:52:41 -12 2024 -I: pbuilder-time-stamp: 1716587561 +I: removing directory /srv/workspace/pbuilder/11513 and its subdirectories +I: Current time: Sun Apr 23 05:32:01 +14 2023 +I: pbuilder-time-stamp: 1682177521