Diff of the two buildlogs: -- --- b1/build.log 2021-08-12 09:37:22.360462482 +0000 +++ b2/build.log 2021-08-12 09:38:41.841502252 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed Aug 11 21:34:45 -12 2021 -I: pbuilder-time-stamp: 1628760885 +I: Current time: Thu Aug 12 23:37:30 +14 2021 +I: pbuilder-time-stamp: 1628761050 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 [./libtest-www-mechanize-mojo-perl_0.0.21-2.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.v7HjzHTA/trustedkeys.kbx': General error -gpgv: Signature made Sat Oct 10 23:44:18 2020 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.Q7kLmm91/trustedkeys.kbx': General error +gpgv: Signature made Mon Oct 12 01:44:18 2020 +14 gpgv: using RSA key D1E1316E93A760A8104D85FABB3A68018649AA06 gpgv: Can't check signature: No public key dpkg-source: warning: failed to verify signature on ./libtest-www-mechanize-mojo-perl_0.0.21-2.dsc @@ -26,135 +26,169 @@ dpkg-source: info: unpacking libtest-www-mechanize-mojo-perl_0.0.21-2.debian.tar.xz I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/29395/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/21062/tmp/hooks/D01_modify_environment starting +debug: Running on virt64b. +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/21062/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/21062/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='21ec480063284afc8a27c6789c70d1bd' - 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='29395' - PS1='# ' - PS2='> ' + INVOCATION_ID=22695483aeb54b52a2b65c8a5b324329 + 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=21062 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.s887vgBfXN/pbuilderrc_VIJs --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.s887vgBfXN/b1 --logfile b1/build.log libtest-www-mechanize-mojo-perl_0.0.21-2.dsc' - SUDO_GID='113' - SUDO_UID='107' - 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.s887vgBfXN/pbuilderrc_HSg1 --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.s887vgBfXN/b2 --logfile b2/build.log --extrapackages usrmerge libtest-www-mechanize-mojo-perl_0.0.21-2.dsc' + SUDO_GID=113 + SUDO_UID=107 + 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 ff4a 5.10.0-8-armmp-lpae #1 SMP Debian 5.10.46-4 (2021-08-03) armv7l GNU/Linux + Linux i-capture-the-hostname 5.10.0-8-arm64 #1 SMP Debian 5.10.46-4 (2021-08-03) aarch64 GNU/Linux I: ls -l /bin total 3580 - -rwxr-xr-x 1 root root 816764 Aug 4 08:25 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 Jul 28 07:09 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 Jul 28 07:09 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 Jul 28 07:09 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 Jul 28 07:09 more - -rwsr-xr-x 1 root root 34400 Jul 28 07:09 mount - -rwxr-xr-x 1 root root 9824 Jul 28 07:09 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 Aug 4 08:25 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 Aug 10 21:29 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 Jul 28 07:09 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 Jul 28 07:09 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 Jul 28 07:09 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/29395/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 816764 Aug 5 10:25 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 Jul 29 09:09 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 Jul 29 09:09 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 Jul 29 09:09 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 Jul 29 09:09 more + -rwsr-xr-x 1 root root 34400 Jul 29 09:09 mount + -rwxr-xr-x 1 root root 9824 Jul 29 09:09 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 Aug 5 10:25 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 Aug 12 23:37 sh -> bash + lrwxrwxrwx 1 root root 4 Aug 11 23:24 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 Jul 29 09:09 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 Jul 29 09:09 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 Jul 29 09:09 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/21062/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -272,7 +306,7 @@ Get: 72 http://deb.debian.org/debian bullseye/main armhf libtest-longstring-perl all 0.17-1.1 [11.6 kB] Get: 73 http://deb.debian.org/debian bullseye/main armhf libwww-mechanize-perl all 2.03-1 [114 kB] Get: 74 http://deb.debian.org/debian bullseye/main armhf libtest-www-mechanize-perl all 1.54-1 [31.0 kB] -Fetched 25.5 MB in 3s (10.2 MB/s) +Fetched 25.5 MB in 2s (10.4 MB/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.) @@ -592,8 +626,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 (1194 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 ... 23275 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/libtest-www-mechanize-mojo-perl-0.0.21/ && 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 > ../libtest-www-mechanize-mojo-perl_0.0.21-2_source.changes +hostname: Name or service not known +I: Running cd /build/libtest-www-mechanize-mojo-perl-0.0.21/ && 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 > ../libtest-www-mechanize-mojo-perl_0.0.21-2_source.changes dpkg-buildpackage: info: source package libtest-www-mechanize-mojo-perl dpkg-buildpackage: info: source version 0.0.21-2 dpkg-buildpackage: info: source distribution unstable @@ -620,12 +691,12 @@ 1..1 ok 1 - Test/WWW/Mechanize/Mojo.pm loaded ok ok -[2021-08-11 21:36:44.43322] [1806] [debug] [dDvqa2HS] GET "/check_auth_basic/" -[2021-08-11 21:36:44.43494] [1806] [debug] [dDvqa2HS] Routing to a callback -[2021-08-11 21:36:44.43618] [1806] [debug] [dDvqa2HS] 200 OK (0.002947s, 339.328/s) -[2021-08-11 21:36:44.47174] [1806] [debug] [Zrw52ppk] GET "/check_auth_basic/" -[2021-08-11 21:36:44.47288] [1806] [debug] [Zrw52ppk] Routing to a callback -[2021-08-11 21:36:44.47397] [1806] [debug] [Zrw52ppk] 401 Unauthorized (0.002167s, 461.467/s) +[2021-08-12 23:38:24.76730] [24959] [debug] [sDEkGBw3] GET "/check_auth_basic/" +[2021-08-12 23:38:24.76846] [24959] [debug] [sDEkGBw3] Routing to a callback +[2021-08-12 23:38:24.76929] [24959] [debug] [sDEkGBw3] 200 OK (0.001989s, 502.765/s) +[2021-08-12 23:38:24.78716] [24959] [debug] [b02MRxah] GET "/check_auth_basic/" +[2021-08-12 23:38:24.78765] [24959] [debug] [b02MRxah] Routing to a callback +[2021-08-12 23:38:24.78815] [24959] [debug] [b02MRxah] 401 Unauthorized (0.000961s, 1040.583/s) t/auth-test.t .... 1..5 ok 1 - GET http://localhost/check_auth_basic/ @@ -634,21 +705,21 @@ ok 4 ok 5 ok -[2021-08-11 21:36:47.09234] [1885] [debug] [cgNaphQ5] GET "/" -[2021-08-11 21:36:47.09712] [1885] [debug] [cgNaphQ5] Routing to a callback -[2021-08-11 21:36:47.09837] [1885] [debug] [cgNaphQ5] 200 OK (0.005995s, 166.806/s) +[2021-08-12 23:38:26.02062] [24960] [debug] [klAYiv4F] GET "/" +[2021-08-12 23:38:26.02267] [24960] [debug] [klAYiv4F] Routing to a callback +[2021-08-12 23:38:26.02333] [24960] [debug] [klAYiv4F] 200 OK (0.002683s, 372.717/s) t/cookies.t ...... 1..3 ok 1 - GET / ok 2 - Title is "Root" ok 3 ok -[2021-08-11 21:36:49.77532] [1989] [debug] [MuxLMOCm] GET "/form" -[2021-08-11 21:36:49.77932] [1989] [debug] [MuxLMOCm] Routing to a callback -[2021-08-11 21:36:49.78066] [1989] [debug] [MuxLMOCm] 200 OK (0.005332s, 187.547/s) -[2021-08-11 21:36:49.82625] [1989] [debug] [RDn-W-6m] POST "/form-submit" -[2021-08-11 21:36:49.82784] [1989] [debug] [RDn-W-6m] Routing to a callback -[2021-08-11 21:36:49.82930] [1989] [debug] [RDn-W-6m] 200 OK (0.002998s, 333.556/s) +[2021-08-12 23:38:27.26215] [24961] [debug] [db3Zivjr] GET "/form" +[2021-08-12 23:38:27.26396] [24961] [debug] [db3Zivjr] Routing to a callback +[2021-08-12 23:38:27.26462] [24961] [debug] [db3Zivjr] 200 OK (0.002463s, 406.009/s) +[2021-08-12 23:38:27.28551] [24961] [debug] [0A5coQeJ] POST "/form-submit" +[2021-08-12 23:38:27.28636] [24961] [debug] [0A5coQeJ] Routing to a callback +[2021-08-12 23:38:27.28711] [24961] [debug] [0A5coQeJ] 200 OK (0.001572s, 636.132/s) t/form_submit.t .. 1..5 ok 1 - GET http://localhost/form @@ -657,36 +728,36 @@ ok 4 - Was able to submit form. ok 5 - Content is like "(?^:Your email is sophie\@hello\.tld)" ok -[2021-08-11 21:36:52.44514] [2090] [debug] [ELOOoxpW] GET "/hi" -[2021-08-11 21:36:52.44721] [2090] [debug] [ELOOoxpW] Routing to a callback -[2021-08-11 21:36:52.44893] [2090] [debug] [ELOOoxpW] 302 Found (0.003714s, 269.251/s) -[2021-08-11 21:36:52.46553] [2090] [debug] [_HGs91Zw] GET "/hello" -[2021-08-11 21:36:52.46748] [2090] [debug] [_HGs91Zw] Routing to a callback -[2021-08-11 21:36:52.46908] [2090] [debug] [_HGs91Zw] 200 OK (0.003478s, 287.522/s) -[2021-08-11 21:36:52.52080] [2090] [debug] [47xlV-1g] GET "/greetings" -[2021-08-11 21:36:52.52239] [2090] [debug] [47xlV-1g] Routing to a callback -[2021-08-11 21:36:52.52382] [2090] [debug] [47xlV-1g] 302 Found (0.00293s, 341.297/s) -[2021-08-11 21:36:52.53974] [2090] [debug] [0dP8CYBr] GET "/hello" -[2021-08-11 21:36:52.54073] [2090] [debug] [0dP8CYBr] Routing to a callback -[2021-08-11 21:36:52.54173] [2090] [debug] [0dP8CYBr] 200 OK (0.001957s, 510.986/s) -[2021-08-11 21:36:52.56917] [2090] [debug] [38IGuNy_] GET "/bonjour" -[2021-08-11 21:36:52.57086] [2090] [debug] [38IGuNy_] Routing to a callback -[2021-08-11 21:36:52.57216] [2090] [debug] [38IGuNy_] 302 Found (0.002892s, 345.781/s) -[2021-08-11 21:36:52.58756] [2090] [debug] [xiyE1XXG] GET "/hi" -[2021-08-11 21:36:52.58849] [2090] [debug] [xiyE1XXG] Routing to a callback -[2021-08-11 21:36:52.58975] [2090] [debug] [xiyE1XXG] 302 Found (0.002108s, 474.383/s) -[2021-08-11 21:36:52.60589] [2090] [debug] [-XEHAbRN] GET "/hello" -[2021-08-11 21:36:52.60678] [2090] [debug] [-XEHAbRN] Routing to a callback -[2021-08-11 21:36:52.60778] [2090] [debug] [-XEHAbRN] 200 OK (0.001816s, 550.661/s) -[2021-08-11 21:36:52.63450] [2090] [debug] [zLMU7QGG] GET "/redirect_with_500" -[2021-08-11 21:36:52.63658] [2090] [debug] [zLMU7QGG] Routing to a callback -[2021-08-11 21:36:52.63778] [2090] [debug] [zLMU7QGG] 302 Found (0.003218s, 310.752/s) -[2021-08-11 21:36:52.64112] [2090] [error] [zLMU7QGG] erk! at t/lib/MyMojjy.pm line 88. +[2021-08-12 23:38:28.46731] [24962] [debug] [dgU1fdgH] GET "/hi" +[2021-08-12 23:38:28.46856] [24962] [debug] [dgU1fdgH] Routing to a callback +[2021-08-12 23:38:28.46928] [24962] [debug] [dgU1fdgH] 302 Found (0.001935s, 516.796/s) +[2021-08-12 23:38:28.47730] [24962] [debug] [h9NlDvCt] GET "/hello" +[2021-08-12 23:38:28.47829] [24962] [debug] [h9NlDvCt] Routing to a callback +[2021-08-12 23:38:28.47889] [24962] [debug] [h9NlDvCt] 200 OK (0.001587s, 630.120/s) +[2021-08-12 23:38:28.50415] [24962] [debug] [UO_jPsiz] GET "/greetings" +[2021-08-12 23:38:28.50491] [24962] [debug] [UO_jPsiz] Routing to a callback +[2021-08-12 23:38:28.50559] [24962] [debug] [UO_jPsiz] 302 Found (0.001403s, 712.758/s) +[2021-08-12 23:38:28.51208] [24962] [debug] [xpAmqu-V] GET "/hello" +[2021-08-12 23:38:28.51252] [24962] [debug] [xpAmqu-V] Routing to a callback +[2021-08-12 23:38:28.51304] [24962] [debug] [xpAmqu-V] 200 OK (0.000929s, 1076.426/s) +[2021-08-12 23:38:28.52453] [24962] [debug] [Ej4n-byW] GET "/bonjour" +[2021-08-12 23:38:28.52527] [24962] [debug] [Ej4n-byW] Routing to a callback +[2021-08-12 23:38:28.52582] [24962] [debug] [Ej4n-byW] 302 Found (0.00126s, 793.651/s) +[2021-08-12 23:38:28.53213] [24962] [debug] [9Yl_utRF] GET "/hi" +[2021-08-12 23:38:28.53255] [24962] [debug] [9Yl_utRF] Routing to a callback +[2021-08-12 23:38:28.53313] [24962] [debug] [9Yl_utRF] 302 Found (0.000925s, 1081.081/s) +[2021-08-12 23:38:28.53971] [24962] [debug] [gsbnSHnF] GET "/hello" +[2021-08-12 23:38:28.54015] [24962] [debug] [gsbnSHnF] Routing to a callback +[2021-08-12 23:38:28.54065] [24962] [debug] [gsbnSHnF] 200 OK (0.000911s, 1097.695/s) +[2021-08-12 23:38:28.55302] [24962] [debug] [KydfMHtY] GET "/redirect_with_500" +[2021-08-12 23:38:28.55396] [24962] [debug] [KydfMHtY] Routing to a callback +[2021-08-12 23:38:28.55454] [24962] [debug] [KydfMHtY] 302 Found (0.001488s, 672.043/s) +[2021-08-12 23:38:28.55619] [24962] [error] [KydfMHtY] erk! at t/lib/MyMojjy.pm line 88. -[2021-08-11 21:36:52.64526] [2090] [debug] [zLMU7QGG] Template "exception.development.html.ep" not found -[2021-08-11 21:36:52.64634] [2090] [debug] [zLMU7QGG] Template "exception.html.ep" not found -[2021-08-11 21:36:52.64735] [2090] [debug] [zLMU7QGG] Rendering template "mojo/debug.html.ep" -[2021-08-11 21:36:52.75045] [2090] [debug] Your secret passphrase needs to be changed +[2021-08-12 23:38:28.55795] [24962] [debug] [KydfMHtY] Template "exception.development.html.ep" not found +[2021-08-12 23:38:28.55845] [24962] [debug] [KydfMHtY] Template "exception.html.ep" not found +[2021-08-12 23:38:28.55890] [24962] [debug] [KydfMHtY] Rendering template "mojo/debug.html.ep" +[2021-08-12 23:38:28.59607] [24962] [debug] Your secret passphrase needs to be changed t/redirect.t ..... 1..28 ok 1 - got something when we hi @@ -718,27 +789,27 @@ ok 27 - to the right place ok 28 - Redirect not followed on 500 ok -[2021-08-11 21:36:55.64273] [2163] [debug] [Tiv5jMiM] GET "/" -[2021-08-11 21:36:55.64761] [2163] [debug] [Tiv5jMiM] Routing to a callback -[2021-08-11 21:36:55.64894] [2163] [debug] [Tiv5jMiM] 200 OK (0.00618s, 161.812/s) -[2021-08-11 21:36:55.69982] [2163] [debug] [FEl4HP-3] GET "/hello/" -[2021-08-11 21:36:55.70150] [2163] [debug] [FEl4HP-3] Routing to a callback -[2021-08-11 21:36:55.70257] [2163] [debug] [FEl4HP-3] 200 OK (0.002685s, 372.439/s) -[2021-08-11 21:36:55.72331] [2163] [debug] [UZl5CmV_] GET "/" -[2021-08-11 21:36:55.72416] [2163] [debug] [UZl5CmV_] Routing to a callback -[2021-08-11 21:36:55.72508] [2163] [debug] [UZl5CmV_] 200 OK (0.00171s, 584.795/s) -[2021-08-11 21:36:55.74242] [2163] [debug] [FDUr6ZZA] GET "/" -[2021-08-11 21:36:55.74324] [2163] [debug] [FDUr6ZZA] Routing to a callback -[2021-08-11 21:36:55.74420] [2163] [debug] [FDUr6ZZA] 200 OK (0.001709s, 585.138/s) -[2021-08-11 21:36:55.76298] [2163] [debug] [9cQz-9d7] GET "/hello/" -[2021-08-11 21:36:55.76384] [2163] [debug] [9cQz-9d7] Routing to a callback -[2021-08-11 21:36:55.76485] [2163] [debug] [9cQz-9d7] 200 OK (0.001815s, 550.964/s) -[2021-08-11 21:36:55.78381] [2163] [debug] [Uojo9uqS] GET "/with-params" -[2021-08-11 21:36:55.78584] [2163] [debug] [Uojo9uqS] Routing to a callback -[2021-08-11 21:36:55.78752] [2163] [debug] [Uojo9uqS] 200 OK (0.003636s, 275.028/s) -[2021-08-11 21:36:55.80374] [2163] [debug] [RSTXjjdO] GET "/with-params" -[2021-08-11 21:36:55.80479] [2163] [debug] [RSTXjjdO] Routing to a callback -[2021-08-11 21:36:55.80632] [2163] [debug] [RSTXjjdO] 200 OK (0.002521s, 396.668/s) +[2021-08-12 23:38:29.76313] [24963] [debug] [YU3nUrRT] GET "/" +[2021-08-12 23:38:29.76517] [24963] [debug] [YU3nUrRT] Routing to a callback +[2021-08-12 23:38:29.76581] [24963] [debug] [YU3nUrRT] 200 OK (0.002659s, 376.081/s) +[2021-08-12 23:38:29.78658] [24963] [debug] [sP8-NJXI] GET "/hello/" +[2021-08-12 23:38:29.78730] [24963] [debug] [sP8-NJXI] Routing to a callback +[2021-08-12 23:38:29.78779] [24963] [debug] [sP8-NJXI] 200 OK (0.001182s, 846.024/s) +[2021-08-12 23:38:29.79593] [24963] [debug] [E9PlPvn3] GET "/" +[2021-08-12 23:38:29.79635] [24963] [debug] [E9PlPvn3] Routing to a callback +[2021-08-12 23:38:29.79680] [24963] [debug] [E9PlPvn3] 200 OK (0.000837s, 1194.743/s) +[2021-08-12 23:38:29.80399] [24963] [debug] [4Bcnlxg1] GET "/" +[2021-08-12 23:38:29.80439] [24963] [debug] [4Bcnlxg1] Routing to a callback +[2021-08-12 23:38:29.80481] [24963] [debug] [4Bcnlxg1] 200 OK (0.000792s, 1262.626/s) +[2021-08-12 23:38:29.81213] [24963] [debug] [rx0BMsL7] GET "/hello/" +[2021-08-12 23:38:29.81253] [24963] [debug] [rx0BMsL7] Routing to a callback +[2021-08-12 23:38:29.81294] [24963] [debug] [rx0BMsL7] 200 OK (0.00078s, 1282.051/s) +[2021-08-12 23:38:29.82085] [24963] [debug] [T_iRt8I1] GET "/with-params" +[2021-08-12 23:38:29.82172] [24963] [debug] [T_iRt8I1] Routing to a callback +[2021-08-12 23:38:29.82246] [24963] [debug] [T_iRt8I1] 200 OK (0.001581s, 632.511/s) +[2021-08-12 23:38:29.82891] [24963] [debug] [1PSwytZ2] GET "/with-params" +[2021-08-12 23:38:29.82941] [24963] [debug] [1PSwytZ2] Routing to a callback +[2021-08-12 23:38:29.83005] [24963] [debug] [1PSwytZ2] 200 OK (0.001111s, 900.090/s) t/simple.t ....... 1..25 ok 1 - GET http://localhost/ @@ -767,20 +838,20 @@ ok 24 - GET /with-params?one=sophie&two=jack ok 25 - Get params (\#2) are OK. ok -[2021-08-11 21:36:58.83305] [2174] [debug] [lN6YvhNm] GET "/user_agent" -[2021-08-11 21:36:58.83776] [2174] [debug] [lN6YvhNm] Routing to a callback -[2021-08-11 21:36:58.83925] [2174] [debug] [lN6YvhNm] 200 OK (0.006157s, 162.417/s) +[2021-08-12 23:38:30.94755] [24964] [debug] [GFDnv-V1] GET "/user_agent" +[2021-08-12 23:38:30.94912] [24964] [debug] [GFDnv-V1] Routing to a callback +[2021-08-12 23:38:30.94978] [24964] [debug] [GFDnv-V1] 200 OK (0.002236s, 447.227/s) t/useragent.t .... 1..2 ok 1 - GET http://localhost/user_agent ok 2 - title is correct: TestAgent/1.0 ok -[2021-08-11 21:37:02.07051] [2226] [debug] [-pS-nfoZ] GET "/host" -[2021-08-11 21:37:02.07527] [2226] [debug] [-pS-nfoZ] Routing to a callback -[2021-08-11 21:37:02.07710] [2226] [debug] [-pS-nfoZ] 200 OK (0.006564s, 152.346/s) -[2021-08-11 21:37:02.12135] [2226] [debug] [KZO60OEm] GET "/host" -[2021-08-11 21:37:02.12232] [2226] [debug] [KZO60OEm] Routing to a callback -[2021-08-11 21:37:02.12367] [2226] [debug] [KZO60OEm] 200 OK (0.00228s, 438.596/s) +[2021-08-12 23:38:32.10816] [24965] [debug] [Z58rvWRp] GET "/host" +[2021-08-12 23:38:32.10979] [24965] [debug] [Z58rvWRp] Routing to a callback +[2021-08-12 23:38:32.11046] [24965] [debug] [Z58rvWRp] 200 OK (0.002299s, 434.972/s) +[2021-08-12 23:38:32.12642] [24965] [debug] [5uut4gu5] GET "/host" +[2021-08-12 23:38:32.12687] [24965] [debug] [5uut4gu5] Routing to a callback +[2021-08-12 23:38:32.12740] [24965] [debug] [5uut4gu5] 200 OK (0.000927s, 1078.749/s) t/white_label.t .. 1..4 ok 1 - GET /host @@ -789,7 +860,7 @@ ok 4 - Content contains "Host: localhost" ok All tests successful. -Files=8, Tests=73, 24 wallclock secs ( 0.17 usr 0.05 sys + 20.93 cusr 1.91 csys = 23.06 CPU) +Files=8, Tests=73, 10 wallclock secs ( 0.10 usr 0.04 sys + 8.98 cusr 0.74 csys = 9.86 CPU) Result: PASS create-stamp debian/debhelper-build-stamp dh_prep @@ -819,12 +890,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/21062/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/21062/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/29395 and its subdirectories -I: Current time: Wed Aug 11 21:37:18 -12 2021 -I: pbuilder-time-stamp: 1628761038 +I: removing directory /srv/workspace/pbuilder/21062 and its subdirectories +I: Current time: Thu Aug 12 23:38:38 +14 2021 +I: pbuilder-time-stamp: 1628761118