Diff of the two buildlogs: -- --- b1/build.log 2021-07-21 15:13:00.290181705 +0000 +++ b2/build.log 2021-07-21 15:17:57.010974862 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed Jul 21 03:08:49 -12 2021 -I: pbuilder-time-stamp: 1626880129 +I: Current time: Thu Jul 22 05:13:11 +14 2021 +I: pbuilder-time-stamp: 1626880391 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -16,8 +16,8 @@ I: copying [./ruby-data-migrate_6.6.0-1.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.ebhY3kyt/trustedkeys.kbx': General error -gpgv: Signature made Thu Jan 21 13:27:16 2021 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.leeUiate/trustedkeys.kbx': General error +gpgv: Signature made Fri Jan 22 15:27:16 2021 +14 gpgv: using RSA key 237A54B1028728BF00EF31F4D0EB762865FC5E36 gpgv: issuer "sergiodj@debian.org" gpgv: Can't check signature: No public key @@ -30,135 +30,169 @@ dpkg-source: info: applying skip-test-failure-load_schema_current.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/9584/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/31058/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 +Removing 'diversion of /bin/sh to /bin/sh.distrib by dash' +Adding 'diversion of /bin/sh to /bin/sh.distrib by bash' +Removing 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by dash' +Adding 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by 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/31058/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/31058/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='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=3' - DISTRIBUTION='' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="1" [2]="4" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.1.4(1)-release' + BUILDDIR=/build + 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,-fixfilepath parallel=4' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='01bcc69e32924ac684b388c1bbb9990f' - 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='9584' - PS1='# ' - PS2='> ' + INVOCATION_ID=2826c29f003f49bd981f8009c2987e1a + 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=31058 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/tmp.kOUFaGByL9/pbuilderrc_cc66 --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.kOUFaGByL9/b1 --logfile b1/build.log ruby-data-migrate_6.6.0-1.dsc' - SUDO_GID='114' - SUDO_UID='108' - 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:8000/' + 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/tmp.kOUFaGByL9/pbuilderrc_O67N --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.kOUFaGByL9/b2 --logfile b2/build.log --extrapackages usrmerge ruby-data-migrate_6.6.0-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:8000/ I: uname -a - Linux virt64a 5.10.0-7-arm64 #1 SMP Debian 5.10.40-1 (2021-05-28) aarch64 GNU/Linux + Linux i-capture-the-hostname 5.10.0-7-armmp #1 SMP Debian 5.10.40-1 (2021-05-28) armv7l GNU/Linux I: ls -l /bin total 3580 - -rwxr-xr-x 1 root root 816764 Jun 21 14:26 bash - -rwxr-xr-x 3 root root 26052 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 26052 Jul 20 2020 bzcat - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Jul 20 2020 bzdiff - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4877 Sep 4 2019 bzexe - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Jul 20 2020 bzgrep - -rwxr-xr-x 3 root root 26052 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 9636 Jul 20 2020 bzip2recover - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Jul 20 2020 bzmore - -rwxr-xr-x 1 root root 26668 Sep 22 2020 cat - -rwxr-xr-x 1 root root 43104 Sep 22 2020 chgrp - -rwxr-xr-x 1 root root 38984 Sep 22 2020 chmod - -rwxr-xr-x 1 root root 43112 Sep 22 2020 chown - -rwxr-xr-x 1 root root 92616 Sep 22 2020 cp - -rwxr-xr-x 1 root root 75524 Dec 10 2020 dash - -rwxr-xr-x 1 root root 75880 Sep 22 2020 date - -rwxr-xr-x 1 root root 55436 Sep 22 2020 dd - -rwxr-xr-x 1 root root 59912 Sep 22 2020 df - -rwxr-xr-x 1 root root 96764 Sep 22 2020 dir - -rwxr-xr-x 1 root root 55012 Feb 7 02:38 dmesg - lrwxrwxrwx 1 root root 8 Nov 6 2019 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Nov 6 2019 domainname -> hostname - -rwxr-xr-x 1 root root 22508 Sep 22 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 22496 Sep 22 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 47492 Feb 7 02:38 findmnt - -rwsr-xr-x 1 root root 26076 Feb 26 04:12 fusermount - -rwxr-xr-x 1 root root 124508 Nov 9 2020 grep - -rwxr-xr-x 2 root root 2346 Mar 2 11:30 gunzip - -rwxr-xr-x 1 root root 6376 Mar 2 11:30 gzexe - -rwxr-xr-x 1 root root 64212 Mar 2 11:30 gzip - -rwxr-xr-x 1 root root 13784 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 43180 Sep 22 2020 ln - -rwxr-xr-x 1 root root 35068 Feb 7 2020 login - -rwxr-xr-x 1 root root 96764 Sep 22 2020 ls - -rwxr-xr-x 1 root root 99940 Feb 7 02:38 lsblk - -rwxr-xr-x 1 root root 51408 Sep 22 2020 mkdir - -rwxr-xr-x 1 root root 43184 Sep 22 2020 mknod - -rwxr-xr-x 1 root root 30780 Sep 22 2020 mktemp - -rwxr-xr-x 1 root root 34408 Feb 7 02:38 more - -rwsr-xr-x 1 root root 34400 Feb 7 02:38 mount - -rwxr-xr-x 1 root root 9824 Feb 7 02:38 mountpoint - -rwxr-xr-x 1 root root 88524 Sep 22 2020 mv - lrwxrwxrwx 1 root root 8 Nov 6 2019 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Apr 18 03:38 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 26652 Sep 22 2020 pwd - lrwxrwxrwx 1 root root 4 Jun 21 14:26 rbash -> bash - -rwxr-xr-x 1 root root 30740 Sep 22 2020 readlink - -rwxr-xr-x 1 root root 43104 Sep 22 2020 rm - -rwxr-xr-x 1 root root 30732 Sep 22 2020 rmdir - -rwxr-xr-x 1 root root 14144 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 76012 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Jul 20 21:25 sh -> dash - -rwxr-xr-x 1 root root 22532 Sep 22 2020 sleep - -rwxr-xr-x 1 root root 55360 Sep 22 2020 stty - -rwsr-xr-x 1 root root 46704 Feb 7 02:38 su - -rwxr-xr-x 1 root root 22532 Sep 22 2020 sync - -rwxr-xr-x 1 root root 340872 Feb 16 21:55 tar - -rwxr-xr-x 1 root root 9808 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 67696 Sep 22 2020 touch - -rwxr-xr-x 1 root root 22496 Sep 22 2020 true - -rwxr-xr-x 1 root root 9636 Feb 26 04:12 ulockmgr_server - -rwsr-xr-x 1 root root 22108 Feb 7 02:38 umount - -rwxr-xr-x 1 root root 22520 Sep 22 2020 uname - -rwxr-xr-x 2 root root 2346 Mar 2 11:30 uncompress - -rwxr-xr-x 1 root root 96764 Sep 22 2020 vdir - -rwxr-xr-x 1 root root 38512 Feb 7 02:38 wdctl - lrwxrwxrwx 1 root root 8 Nov 6 2019 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Mar 2 11:30 zcat - -rwxr-xr-x 1 root root 1678 Mar 2 11:30 zcmp - -rwxr-xr-x 1 root root 5880 Mar 2 11:30 zdiff - -rwxr-xr-x 1 root root 29 Mar 2 11:30 zegrep - -rwxr-xr-x 1 root root 29 Mar 2 11:30 zfgrep - -rwxr-xr-x 1 root root 2081 Mar 2 11:30 zforce - -rwxr-xr-x 1 root root 7585 Mar 2 11:30 zgrep - -rwxr-xr-x 1 root root 2206 Mar 2 11:30 zless - -rwxr-xr-x 1 root root 1842 Mar 2 11:30 zmore - -rwxr-xr-x 1 root root 4553 Mar 2 11:30 znew -I: user script /srv/workspace/pbuilder/9584/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 816764 Jun 22 16:26 bash + -rwxr-xr-x 3 root root 26052 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 26052 Jul 21 2020 bzcat + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Jul 21 2020 bzdiff + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4877 Sep 5 2019 bzexe + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Jul 21 2020 bzgrep + -rwxr-xr-x 3 root root 26052 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 9636 Jul 21 2020 bzip2recover + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Jul 21 2020 bzmore + -rwxr-xr-x 1 root root 26668 Sep 23 2020 cat + -rwxr-xr-x 1 root root 43104 Sep 23 2020 chgrp + -rwxr-xr-x 1 root root 38984 Sep 23 2020 chmod + -rwxr-xr-x 1 root root 43112 Sep 23 2020 chown + -rwxr-xr-x 1 root root 92616 Sep 23 2020 cp + -rwxr-xr-x 1 root root 75524 Dec 11 2020 dash + -rwxr-xr-x 1 root root 75880 Sep 23 2020 date + -rwxr-xr-x 1 root root 55436 Sep 23 2020 dd + -rwxr-xr-x 1 root root 59912 Sep 23 2020 df + -rwxr-xr-x 1 root root 96764 Sep 23 2020 dir + -rwxr-xr-x 1 root root 55012 Feb 8 04:38 dmesg + lrwxrwxrwx 1 root root 8 Nov 8 2019 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Nov 8 2019 domainname -> hostname + -rwxr-xr-x 1 root root 22508 Sep 23 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 22496 Sep 23 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 47492 Feb 8 04:38 findmnt + -rwsr-xr-x 1 root root 26076 Feb 27 06:12 fusermount + -rwxr-xr-x 1 root root 124508 Nov 10 2020 grep + -rwxr-xr-x 2 root root 2346 Mar 3 13:30 gunzip + -rwxr-xr-x 1 root root 6376 Mar 3 13:30 gzexe + -rwxr-xr-x 1 root root 64212 Mar 3 13:30 gzip + -rwxr-xr-x 1 root root 13784 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 43180 Sep 23 2020 ln + -rwxr-xr-x 1 root root 35068 Feb 8 2020 login + -rwxr-xr-x 1 root root 96764 Sep 23 2020 ls + -rwxr-xr-x 1 root root 99940 Feb 8 04:38 lsblk + -rwxr-xr-x 1 root root 51408 Sep 23 2020 mkdir + -rwxr-xr-x 1 root root 43184 Sep 23 2020 mknod + -rwxr-xr-x 1 root root 30780 Sep 23 2020 mktemp + -rwxr-xr-x 1 root root 34408 Feb 8 04:38 more + -rwsr-xr-x 1 root root 34400 Feb 8 04:38 mount + -rwxr-xr-x 1 root root 9824 Feb 8 04:38 mountpoint + -rwxr-xr-x 1 root root 88524 Sep 23 2020 mv + lrwxrwxrwx 1 root root 8 Nov 8 2019 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 19 05:38 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 26652 Sep 23 2020 pwd + lrwxrwxrwx 1 root root 4 Jun 22 16:26 rbash -> bash + -rwxr-xr-x 1 root root 30740 Sep 23 2020 readlink + -rwxr-xr-x 1 root root 43104 Sep 23 2020 rm + -rwxr-xr-x 1 root root 30732 Sep 23 2020 rmdir + -rwxr-xr-x 1 root root 14144 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 76012 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Jul 22 05:14 sh -> bash + lrwxrwxrwx 1 root root 4 Jul 21 23:29 sh.distrib -> dash + -rwxr-xr-x 1 root root 22532 Sep 23 2020 sleep + -rwxr-xr-x 1 root root 55360 Sep 23 2020 stty + -rwsr-xr-x 1 root root 46704 Feb 8 04:38 su + -rwxr-xr-x 1 root root 22532 Sep 23 2020 sync + -rwxr-xr-x 1 root root 340872 Feb 17 23:55 tar + -rwxr-xr-x 1 root root 9808 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 67696 Sep 23 2020 touch + -rwxr-xr-x 1 root root 22496 Sep 23 2020 true + -rwxr-xr-x 1 root root 9636 Feb 27 06:12 ulockmgr_server + -rwsr-xr-x 1 root root 22108 Feb 8 04:38 umount + -rwxr-xr-x 1 root root 22520 Sep 23 2020 uname + -rwxr-xr-x 2 root root 2346 Mar 3 13:30 uncompress + -rwxr-xr-x 1 root root 96764 Sep 23 2020 vdir + -rwxr-xr-x 1 root root 38512 Feb 8 04:38 wdctl + lrwxrwxrwx 1 root root 8 Nov 8 2019 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Mar 3 13:30 zcat + -rwxr-xr-x 1 root root 1678 Mar 3 13:30 zcmp + -rwxr-xr-x 1 root root 5880 Mar 3 13:30 zdiff + -rwxr-xr-x 1 root root 29 Mar 3 13:30 zegrep + -rwxr-xr-x 1 root root 29 Mar 3 13:30 zfgrep + -rwxr-xr-x 1 root root 2081 Mar 3 13:30 zforce + -rwxr-xr-x 1 root root 7585 Mar 3 13:30 zgrep + -rwxr-xr-x 1 root root 2206 Mar 3 13:30 zless + -rwxr-xr-x 1 root root 1842 Mar 3 13:30 zmore + -rwxr-xr-x 1 root root 4553 Mar 3 13:30 znew +I: user script /srv/workspace/pbuilder/31058/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -407,7 +441,7 @@ Get: 191 http://deb.debian.org/debian bullseye/main armhf ruby-rspec all 3.9.0c2e2m1s3-2 [8348 B] Get: 192 http://deb.debian.org/debian bullseye/main armhf ruby-sqlite3 armhf 1.4.2-3 [42.1 kB] Get: 193 http://deb.debian.org/debian bullseye/main armhf ruby-timecop all 0.9.1-1 [10.2 kB] -Fetched 47.3 MB in 7s (6352 kB/s) +Fetched 47.3 MB in 7s (6993 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package bsdextrautils. (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 ... 19398 files and directories currently installed.) @@ -1203,8 +1237,45 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +The following additional packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl +The following NEW packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl usrmerge +0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. +Need to get 59.5 kB of archives. +After this operation, 157 kB of additional disk space will be used. +Get:1 http://deb.debian.org/debian bullseye/main armhf libnumber-compare-perl all 0.03-1.1 [6956 B] +Get:2 http://deb.debian.org/debian bullseye/main armhf libtext-glob-perl all 0.11-1 [8888 B] +Get:3 http://deb.debian.org/debian bullseye/main armhf libfile-find-rule-perl all 0.34-1 [30.6 kB] +Get:4 http://deb.debian.org/debian bullseye/main armhf usrmerge all 25 [13.0 kB] +debconf: delaying package configuration, since apt-utils is not installed +Fetched 59.5 kB in 0s (152 kB/s) +Selecting previously unselected package libnumber-compare-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 ... 31586 files and directories currently installed.) +Preparing to unpack .../libnumber-compare-perl_0.03-1.1_all.deb ... +Unpacking libnumber-compare-perl (0.03-1.1) ... +Selecting previously unselected package libtext-glob-perl. +Preparing to unpack .../libtext-glob-perl_0.11-1_all.deb ... +Unpacking libtext-glob-perl (0.11-1) ... +Selecting previously unselected package libfile-find-rule-perl. +Preparing to unpack .../libfile-find-rule-perl_0.34-1_all.deb ... +Unpacking libfile-find-rule-perl (0.34-1) ... +Selecting previously unselected package usrmerge. +Preparing to unpack .../archives/usrmerge_25_all.deb ... +Unpacking usrmerge (25) ... +Setting up libtext-glob-perl (0.11-1) ... +Setting up libnumber-compare-perl (0.03-1.1) ... +Setting up libfile-find-rule-perl (0.34-1) ... +Setting up usrmerge (25) ... +The system has been successfully converted. +Processing triggers for man-db (2.9.4-2) ... +Not building database; man-db/auto-update is not 'true'. I: Building the package -I: Running cd /build/ruby-data-migrate-6.6.0/ && 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 > ../ruby-data-migrate_6.6.0-1_source.changes +hostname: Name or service not known +I: Running cd /build/ruby-data-migrate-6.6.0/ && 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 > ../ruby-data-migrate_6.6.0-1_source.changes dpkg-buildpackage: info: source package ruby-data-migrate dpkg-buildpackage: info: source version 6.6.0-1 dpkg-buildpackage: info: source distribution unstable @@ -1235,7 +1306,7 @@ dh_auto_install -O--buildsystem=ruby dh_ruby --install /build/ruby-data-migrate-6.6.0/debian/ruby-data-migrate dh_ruby --install -/usr/bin/ruby2.7 -S gem build --config-file /dev/null --verbose /tmp/d20210721-16192-1hpx40u/gemspec +/usr/bin/ruby2.7 -S gem build --config-file /dev/null --verbose /tmp/d20210722-6630-fm6u0d/gemspec Failed to load /dev/null because it doesn't contain valid YAML hash WARNING: description and summary are identical WARNING: open-ended dependency on rails (>= 5.0) is not recommended @@ -1264,7 +1335,7 @@ Name: data_migrate Version: 6.6.0 File: data_migrate-6.6.0.gem -/usr/bin/ruby2.7 -S gem install --config-file /dev/null --verbose --local --verbose --no-document --ignore-dependencies --install-dir debian/ruby-data-migrate/usr/share/rubygems-integration/all /tmp/d20210721-16192-1hpx40u/data_migrate-6.6.0.gem +/usr/bin/ruby2.7 -S gem install --config-file /dev/null --verbose --local --verbose --no-document --ignore-dependencies --install-dir debian/ruby-data-migrate/usr/share/rubygems-integration/all /tmp/d20210722-6630-fm6u0d/data_migrate-6.6.0.gem Failed to load /dev/null because it doesn't contain valid YAML hash /build/ruby-data-migrate-6.6.0/debian/ruby-data-migrate/usr/share/rubygems-integration/all/gems/data_migrate-6.6.0/lib/capistrano/data_migrate.rb /build/ruby-data-migrate-6.6.0/debian/ruby-data-migrate/usr/share/rubygems-integration/all/gems/data_migrate-6.6.0/lib/capistrano/data_migrate/migrate.rb @@ -1309,7 +1380,7 @@ rm -rf extensions cd gems/data_migrate-6.6.0 rm -f -chmod 644 lib/generators/data_migration/templates/migration.rb lib/generators/data_migration/templates/data_migration.rb lib/generators/data_migration/data_migration_generator.rb lib/generators/data_migrate.rb lib/data_migrate.rb lib/data_migrate/schema_migration_five.rb lib/data_migrate/schema_migration_six.rb lib/data_migrate/status_service.rb lib/data_migrate/version.rb lib/data_migrate/data_schema.rb lib/data_migrate/migration.rb lib/data_migrate/railtie.rb lib/data_migrate/database_tasks.rb lib/data_migrate/legacy_migrator.rb lib/data_migrate/config.rb lib/data_migrate/data_migrator_five.rb lib/data_migrate/schema_dumper.rb lib/data_migrate/migration_context.rb lib/data_migrate/data_schema_migration.rb lib/data_migrate/data_migrator.rb lib/data_migrate/tasks/data_migrate_tasks.rb lib/data_migrate/schema_migration.rb lib/data_migrate/migration_five.rb lib/data_migrate/status_service_five.rb lib/capistrano/data_migrate.rb lib/capistrano/data_migrate/migrate.rb +chmod 644 lib/capistrano/data_migrate/migrate.rb lib/capistrano/data_migrate.rb lib/generators/data_migration/data_migration_generator.rb lib/generators/data_migration/templates/data_migration.rb lib/generators/data_migration/templates/migration.rb lib/generators/data_migrate.rb lib/data_migrate/config.rb lib/data_migrate/schema_migration_six.rb lib/data_migrate/schema_dumper.rb lib/data_migrate/status_service_five.rb lib/data_migrate/railtie.rb lib/data_migrate/migration_context.rb lib/data_migrate/data_schema_migration.rb lib/data_migrate/legacy_migrator.rb lib/data_migrate/version.rb lib/data_migrate/data_migrator_five.rb lib/data_migrate/migration_five.rb lib/data_migrate/data_migrator.rb lib/data_migrate/status_service.rb lib/data_migrate/migration.rb lib/data_migrate/schema_migration.rb lib/data_migrate/schema_migration_five.rb lib/data_migrate/data_schema.rb lib/data_migrate/tasks/data_migrate_tasks.rb lib/data_migrate/database_tasks.rb lib/data_migrate.rb find lib/ -type d -empty -delete cd - cd - @@ -1336,14 +1407,14 @@ DataMigrate::DataMigrator load_migrated -- drop_table("data_migrations") - -> 0.0275s + -> 0.0158s -- drop_table("schema_migrations") - -> 0.0276s + -> 0.0136s is expected to include 20110000000000 assure_data_schema_table -- drop_table("data_migrations") -- drop_table("data_migrations") - -> 0.0573s + -> 0.0134s is expected to eq true #migrations_status returns all migrations statuses @@ -1363,38 +1434,38 @@ define when no version is supplied -- drop_table("data_migrations") - -> 0.0592s + -> 0.0117s returns nil when a version is supplied -- quote_table_name("data_migrations") - -> 0.0019s + -> 0.0004s -- select_values("SELECT version FROM \"data_migrations\"") - -> 0.0100s + -> 0.0024s -- quote_table_name("data_migrations") - -> 0.0004s + -> 0.0003s -- execute("INSERT INTO \"data_migrations\" (version) VALUES ('20101231235959')") - -> 0.0487s + -> 0.0102s -- quote_table_name("data_migrations") - -> 0.0005s + -> 0.0004s -- execute("INSERT INTO \"data_migrations\" (version) VALUES ('20091231235959')") - -> 0.0661s + -> 0.0163s -- drop_table("data_migrations") - -> 0.0317s + -> 0.0110s sets the current version to the supplied version -- quote_table_name("data_migrations") - -> 0.0002s + -> 0.0005s -- select_values("SELECT version FROM \"data_migrations\"") - -> 0.0007s + -> 0.0025s -- quote_table_name("data_migrations") - -> 0.0001s + -> 0.0006s -- execute("INSERT INTO \"data_migrations\" (version) VALUES ('20101231235959')") - -> 0.0343s + -> 0.0219s -- quote_table_name("data_migrations") - -> 0.0001s + -> 0.0003s -- execute("INSERT INTO \"data_migrations\" (version) VALUES ('20091231235959')") - -> 0.0248s + -> 0.0312s -- drop_table("data_migrations") - -> 0.0216s + -> 0.0140s creates entries for migration versions that come before the supplied version DataMigrate::DatabaseTasks @@ -1407,17 +1478,17 @@ == Data ======================================================================= == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0000s) ================================ +== 20091231235959 SomeName: migrated (0.0003s) ================================ -- drop_table("data_migrations") - -> 0.0193s + -> 0.0155s -- drop_table("schema_migrations") - -> 0.0363s + -> 0.0127s is expected to eq 20091231235959 DEPRECATION WARNING: Setting `ActiveRecord::Base.configurations` with `[]=` is deprecated. Use `ActiveRecord::Base.configurations=` directly to set the configurations instead. (called from block (2 levels) in at /build/ruby-data-migrate-6.6.0/spec/data_migrate/database_tasks_spec.rb:42) -- drop_table("data_migrations") -- drop_table("schema_migrations") - -> 0.0826s + -> 0.0126s shows nothing without any migrations load_schema_current loads the current schema file (PENDING: Temporarily skipped with xit) @@ -1430,33 +1501,33 @@ == 20091231235959 SomeName: migrated (0.0001s) ================================ -- drop_table("data_migrations") - -> 0.0764s + -> 0.0159s -- drop_table("schema_migrations") - -> 0.0522s + -> 0.0105s run forward default amount of times DEPRECATION WARNING: Setting `ActiveRecord::Base.configurations` with `[]=` is deprecated. Use `ActiveRecord::Base.configurations=` directly to set the configurations instead. (called from block (2 levels) in at /build/ruby-data-migrate-6.6.0/spec/data_migrate/database_tasks_spec.rb:42) == Data ======================================================================= == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0000s) ================================ +== 20091231235959 SomeName: migrated (0.0001s) ================================ == Schema ===================================================================== == 20131111111111 LateMigration: migrating ==================================== Doing schema LateMigration -== 20131111111111 LateMigration: migrated (0.0000s) =========================== +== 20131111111111 LateMigration: migrated (0.0001s) =========================== -- drop_table("data_migrations") - -> 0.0467s + -> 0.0138s -- drop_table("schema_migrations") - -> 0.0568s + -> 0.0128s run forward defined number of times DataMigrate::LegacyMigrator Creating 20091231235959 in data schema -- drop_table("data_migrations") - -> 0.0379s + -> 0.0238s -- drop_table("schema_migrations") - -> 0.0468s + -> 0.0250s migrate legacy migrations to be in correct table DataMigrate::DataMigrator @@ -1470,41 +1541,41 @@ == 20171231235959 SuperUpdate: migrated (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0296s + -> 0.0129s -- drop_table("schema_migrations") - -> 0.0281s + -> 0.0127s -- drop_table("data_migrations") migrates existing file == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0001s) ================================ +== 20091231235959 SomeName: migrated (0.0002s) ================================ == 20171231235959 SuperUpdate: migrating ====================================== Doing SuperUpdate -== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0002s) ============================= == 20171231235959 SuperUpdate: reverting ====================================== Undoing SuperUpdate -== 20171231235959 SuperUpdate: reverted (0.0003s) ============================= +== 20171231235959 SuperUpdate: reverted (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0598s + -> 0.0123s -- drop_table("schema_migrations") - -> 0.0904s + -> 0.0147s -- drop_table("data_migrations") undo migration == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0000s) ================================ +== 20091231235959 SomeName: migrated (0.0001s) ================================ == 20171231235959 SuperUpdate: migrating ====================================== Doing SuperUpdate -== 20171231235959 SuperUpdate: migrated (0.0000s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0290s + -> 0.0141s -- drop_table("schema_migrations") - -> 0.0238s + -> 0.0144s -- drop_table("data_migrations") does not do anything if migration is undone twice == 20171231235959 SuperUpdate: migrating ====================================== @@ -1512,21 +1583,21 @@ == 20171231235959 SuperUpdate: migrated (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0548s + -> 0.0109s -- drop_table("schema_migrations") - -> 0.0457s + -> 0.0120s -- drop_table("data_migrations") runs a specific migration -- drop_table("data_migrations") - -> 0.0342s + -> 0.0163s -- drop_table("schema_migrations") - -> 0.0203s + -> 0.0125s -- drop_table("data_migrations") does not do anything if migration is ran twice -- drop_table("data_migrations") - -> 0.0491s + -> 0.0140s -- drop_table("schema_migrations") - -> 0.0546s + -> 0.0112s -- drop_table("data_migrations") alerts for an invalid specific migration == 20091231235959 SomeName: migrating ========================================= @@ -1535,72 +1606,72 @@ == 20171231235959 SuperUpdate: migrating ====================================== Doing SuperUpdate -== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0002s) ============================= -- drop_table("data_migrations") - -> 0.0310s + -> 0.0196s -- drop_table("schema_migrations") - -> 0.0384s + -> 0.0225s -- drop_table("data_migrations") rolls back latest migration == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0000s) ================================ +== 20091231235959 SomeName: migrated (0.0001s) ================================ == 20171231235959 SuperUpdate: migrating ====================================== Doing SuperUpdate -== 20171231235959 SuperUpdate: migrated (0.0000s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= == 20131111111111 LateMigration: migrating ==================================== Doing schema LateMigration -== 20131111111111 LateMigration: migrated (0.0000s) =========================== +== 20131111111111 LateMigration: migrated (0.0001s) =========================== == 20202020202011 DbMigration: migrating ====================================== Doing schema migration -== 20202020202011 DbMigration: migrated (0.0000s) ============================= +== 20202020202011 DbMigration: migrated (0.0003s) ============================= -- drop_table("data_migrations") - -> 0.0320s + -> 0.0148s -- drop_table("schema_migrations") - -> 0.0294s + -> 0.0172s -- drop_table("data_migrations") rolls back 2 migrations == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0002s) ================================ +== 20091231235959 SomeName: migrated (0.0001s) ================================ == 20171231235959 SuperUpdate: migrating ====================================== Doing SuperUpdate -== 20171231235959 SuperUpdate: migrated (0.0000s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0297s + -> 0.0117s -- drop_table("schema_migrations") - -> 0.0241s + -> 0.0131s -- drop_table("data_migrations") rolls back 2 migrations DataMigrate::SchemaDumper dump -- drop_table("data_migrations") - -> 0.0435s + -> 0.0299s writes the define method with the version key to the stream DataMigrate::SchemaMigration pending_schema_migrations -- drop_table("schema_migrations") - -> 0.0746s + -> 0.0122s list sorted schema migrations run -- drop_table("schema_migrations") - -> 0.0297s + -> 0.0798s is expected to eq "20202020202011" == 20202020202011 DbMigration: migrating ====================================== Doing schema migration -== 20202020202011 DbMigration: migrated (0.0000s) ============================= +== 20202020202011 DbMigration: migrated (0.0002s) ============================= -- drop_table("schema_migrations") - -> 0.0208s + -> 0.0165s undo migration DataMigrate::StatusService @@ -1608,19 +1679,19 @@ show error message table exists -- drop_table("data_migrations") - -> 0.0365s + -> 0.0180s shows successfully executed migration -- drop_table("data_migrations") - -> 0.0223s + -> 0.0171s excludes files without .rb extension -- drop_table("data_migrations") - -> 0.0461s + -> 0.0129s shows missing file migration -- drop_table("data_migrations") - -> 0.0488s + -> 0.0147s shows migration that has not run yet -- drop_table("data_migrations") - -> 0.0375s + -> 0.0156s outputs migrations in chronological order DataMigrate::Tasks::DataMigrateTasks @@ -1628,33 +1699,33 @@ when not given a separate db config == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0002s) ================================ +== 20091231235959 SomeName: migrated (0.0001s) ================================ == 20171231235959 SuperUpdate: migrating ====================================== Doing data migration -== 20171231235959 SuperUpdate: migrated (0.0000s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0298s + -> 0.0101s does not override the default connection when given == 20091231235959 SomeName: migrating ========================================= Doing data migration -== 20091231235959 SomeName: migrated (0.0000s) ================================ +== 20091231235959 SomeName: migrated (0.0001s) ================================ == 20171231235959 SuperUpdate: migrating ====================================== Doing data migration -== 20171231235959 SuperUpdate: migrated (0.0000s) ============================= +== 20171231235959 SuperUpdate: migrated (0.0001s) ============================= -- drop_table("data_migrations") - -> 0.0246s + -> 0.0112s overrides the default connection migrate -- drop_table("data_migrations") - -> 0.0448s + -> 0.0724s is expected to output /20091231235959 SomeName: migrating/ to stdout -- drop_table("data_migrations") - -> 0.0426s + -> 0.0134s is expected to output /20171231235959 SuperUpdate: migrating/ to stdout abort_if_pending_migrations when there are no pending migrations @@ -1684,7 +1755,7 @@ # Temporarily skipped with xit # ./spec/data_migrate/database_tasks_spec.rb:106 -Finished in 9.25 seconds (files took 8.63 seconds to load) +Finished in 6.07 seconds (files took 9.04 seconds to load) 49 examples, 0 failures, 2 pending @@ -1717,12 +1788,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/31058/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/31058/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/9584 and its subdirectories -I: Current time: Wed Jul 21 03:12:56 -12 2021 -I: pbuilder-time-stamp: 1626880376 +I: removing directory /srv/workspace/pbuilder/31058 and its subdirectories +I: Current time: Thu Jul 22 05:17:53 +14 2021 +I: pbuilder-time-stamp: 1626880673