Diff of the two buildlogs: -- --- b1/build.log 2020-05-16 20:32:44.877587688 +0000 +++ b2/build.log 2020-05-16 20:38:06.611152032 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Jun 18 14:55:23 -12 2021 -I: pbuilder-time-stamp: 1624071323 +I: Current time: Sun May 17 10:32:47 +14 2020 +I: pbuilder-time-stamp: 1589661167 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/buster-reproducible-base.tgz] I: copying local configuration @@ -18,7 +18,7 @@ I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' gpgv: keyblock resource '/root/.gnupg/trustedkeys.kbx': General error -gpgv: Signature made Sat Dec 22 07:24:20 2018 -12 +gpgv: Signature made Sun Dec 23 09:24:20 2018 +14 gpgv: using RSA key D1E1316E93A760A8104D85FABB3A68018649AA06 gpgv: Can't check signature: No public key dpkg-source: warning: failed to verify signature on ./libmojolicious-plugin-cgi-perl_0.40-1.dsc @@ -27,6 +27,172 @@ dpkg-source: info: unpacking libmojolicious-plugin-cgi-perl_0.40-1.debian.tar.xz I: using fakeroot in build. I: Installing the build-deps +I: user script /srv/workspace/pbuilder/24445/tmp/hooks/D01_modify_environment starting +debug: Running on profitbricks-build2-i386. +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/24445/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/24445/tmp/hooks/D02_print_environment starting +I: set + 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]="0" [2]="3" [3]="1" [4]="release" [5]="i686-pc-linux-gnu") + BASH_VERSION='5.0.3(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=i386 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=10' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 + IFS=' + ' + INVOCATION_ID=dd583ec7195744e9946f68668b493a9b + LANG=C + LANGUAGE=de_CH:de + LC_ALL=C + LD_LIBRARY_PATH=/usr/lib/libeatmydata + LD_PRELOAD=libeatmydata.so + MACHTYPE=i686-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=24445 + PS4='+ ' + 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.HTP6nujDyY/pbuilderrc_g122 --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/buster-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.HTP6nujDyY/b2 --logfile b2/build.log libmojolicious-plugin-cgi-perl_0.40-1.dsc' + SUDO_GID=112 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://78.137.99.97:3128 +I: uname -a + Linux i-capture-the-hostname 4.19.0-9-686-pae #1 SMP Debian 4.19.118-2 (2020-04-29) i686 GNU/Linux +I: ls -l /bin + total 5476 + -rwxr-xr-x 1 root root 1302248 Apr 18 2019 bash + -rwxr-xr-x 3 root root 38280 Jul 11 2019 bunzip2 + -rwxr-xr-x 3 root root 38280 Jul 11 2019 bzcat + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2227 Jul 11 2019 bzdiff + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4877 Jun 25 2019 bzexe + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3641 Jul 11 2019 bzgrep + -rwxr-xr-x 3 root root 38280 Jul 11 2019 bzip2 + -rwxr-xr-x 1 root root 17768 Jul 11 2019 bzip2recover + lrwxrwxrwx 1 root root 6 Jul 11 2019 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Jul 11 2019 bzmore + -rwxr-xr-x 1 root root 38692 Mar 1 2019 cat + -rwxr-xr-x 1 root root 75588 Mar 1 2019 chgrp + -rwxr-xr-x 1 root root 63268 Mar 1 2019 chmod + -rwxr-xr-x 1 root root 75588 Mar 1 2019 chown + -rwxr-xr-x 1 root root 153732 Mar 1 2019 cp + -rwxr-xr-x 1 root root 132820 Jan 18 2019 dash + -rwxr-xr-x 1 root root 120676 Mar 1 2019 date + -rwxr-xr-x 1 root root 92040 Mar 1 2019 dd + -rwxr-xr-x 1 root root 100620 Mar 1 2019 df + -rwxr-xr-x 1 root root 149736 Mar 1 2019 dir + -rwxr-xr-x 1 root root 79412 Jan 10 2019 dmesg + lrwxrwxrwx 1 root root 8 Sep 27 2018 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Sep 27 2018 domainname -> hostname + -rwxr-xr-x 1 root root 34532 Mar 1 2019 echo + -rwxr-xr-x 1 root root 28 Jan 8 2019 egrep + -rwxr-xr-x 1 root root 34532 Mar 1 2019 false + -rwxr-xr-x 1 root root 28 Jan 8 2019 fgrep + -rwxr-xr-x 1 root root 67700 Jan 10 2019 findmnt + -rwsr-xr-x 1 root root 30112 Apr 23 09:38 fusermount + -rwxr-xr-x 1 root root 206392 Jan 8 2019 grep + -rwxr-xr-x 2 root root 2345 Jan 6 2019 gunzip + -rwxr-xr-x 1 root root 6375 Jan 6 2019 gzexe + -rwxr-xr-x 1 root root 100952 Jan 6 2019 gzip + -rwxr-xr-x 1 root root 21916 Sep 27 2018 hostname + -rwxr-xr-x 1 root root 79752 Mar 1 2019 ln + -rwxr-xr-x 1 root root 55400 Jul 27 2018 login + -rwxr-xr-x 1 root root 149736 Mar 1 2019 ls + -rwxr-xr-x 1 root root 112032 Jan 10 2019 lsblk + -rwxr-xr-x 1 root root 87972 Mar 1 2019 mkdir + -rwxr-xr-x 1 root root 79748 Mar 1 2019 mknod + -rwxr-xr-x 1 root root 46916 Mar 1 2019 mktemp + -rwxr-xr-x 1 root root 42348 Jan 10 2019 more + -rwsr-xr-x 1 root root 50592 Jan 10 2019 mount + -rwxr-xr-x 1 root root 13724 Jan 10 2019 mountpoint + -rwxr-xr-x 1 root root 157832 Mar 1 2019 mv + lrwxrwxrwx 1 root root 8 Sep 27 2018 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Feb 15 2019 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 38660 Mar 1 2019 pwd + lrwxrwxrwx 1 root root 4 Apr 18 2019 rbash -> bash + -rwxr-xr-x 1 root root 46852 Mar 1 2019 readlink + -rwxr-xr-x 1 root root 75588 Mar 1 2019 rm + -rwxr-xr-x 1 root root 42756 Mar 1 2019 rmdir + -rwxr-xr-x 1 root root 22276 Jan 22 2019 run-parts + -rwxr-xr-x 1 root root 125036 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 May 17 10:36 sh -> bash + lrwxrwxrwx 1 root root 4 May 15 22:26 sh.distrib -> dash + -rwxr-xr-x 1 root root 34532 Mar 1 2019 sleep + -rwxr-xr-x 1 root root 79652 Mar 1 2019 stty + -rwsr-xr-x 1 root root 71072 Jan 10 2019 su + -rwxr-xr-x 1 root root 34564 Mar 1 2019 sync + -rwxr-xr-x 1 root root 504024 Apr 24 2019 tar + -rwxr-xr-x 1 root root 13860 Jan 22 2019 tempfile + -rwxr-xr-x 1 root root 104292 Mar 1 2019 touch + -rwxr-xr-x 1 root root 34532 Mar 1 2019 true + -rwxr-xr-x 1 root root 17768 Apr 23 09:38 ulockmgr_server + -rwsr-xr-x 1 root root 30108 Jan 10 2019 umount + -rwxr-xr-x 1 root root 34532 Mar 1 2019 uname + -rwxr-xr-x 2 root root 2345 Jan 6 2019 uncompress + -rwxr-xr-x 1 root root 149736 Mar 1 2019 vdir + -rwxr-xr-x 1 root root 34208 Jan 10 2019 wdctl + -rwxr-xr-x 1 root root 946 Jan 22 2019 which + lrwxrwxrwx 1 root root 8 Sep 27 2018 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1983 Jan 6 2019 zcat + -rwxr-xr-x 1 root root 1677 Jan 6 2019 zcmp + -rwxr-xr-x 1 root root 5879 Jan 6 2019 zdiff + -rwxr-xr-x 1 root root 29 Jan 6 2019 zegrep + -rwxr-xr-x 1 root root 29 Jan 6 2019 zfgrep + -rwxr-xr-x 1 root root 2080 Jan 6 2019 zforce + -rwxr-xr-x 1 root root 7584 Jan 6 2019 zgrep + -rwxr-xr-x 1 root root 2205 Jan 6 2019 zless + -rwxr-xr-x 1 root root 1841 Jan 6 2019 zmore + -rwxr-xr-x 1 root root 4552 Jan 6 2019 znew +I: user script /srv/workspace/pbuilder/24445/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -103,7 +269,7 @@ Get: 35 http://deb.debian.org/debian buster/main i386 libjs-jquery all 3.3.1~dfsg-3 [332 kB] Get: 36 http://deb.debian.org/debian buster/main i386 libjs-prettify all 2015.12.04+dfsg-1.1 [41.7 kB] Get: 37 http://deb.debian.org/debian buster/main i386 libmojolicious-perl all 8.12+dfsg-1 [793 kB] -Fetched 20.5 MB in 0s (69.5 MB/s) +Fetched 20.5 MB in 12s (1720 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libbsd0:i386. (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 ... 19234 files and directories currently installed.) @@ -274,7 +440,7 @@ fakeroot is already the newest version (1.23-1). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/libmojolicious-plugin-cgi-perl-0.40/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b +I: Running cd /build/libmojolicious-plugin-cgi-perl-0.40/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b dpkg-buildpackage: info: source package libmojolicious-plugin-cgi-perl dpkg-buildpackage: info: source version 0.40-1 dpkg-buildpackage: info: source distribution unstable @@ -296,43 +462,43 @@ Writing Makefile for Mojolicious::Plugin::CGI Writing MYMETA.yml and MYMETA.json dh_auto_build - make -j18 + make -j10 make[1]: Entering directory '/build/libmojolicious-plugin-cgi-perl-0.40' cp lib/Mojolicious/Plugin/CGI.pm blib/lib/Mojolicious/Plugin/CGI.pm Manifying 1 pod document make[1]: Leaving directory '/build/libmojolicious-plugin-cgi-perl-0.40' dh_auto_test - make -j18 test TEST_VERBOSE=1 + make -j10 test TEST_VERBOSE=1 make[1]: Entering directory '/build/libmojolicious-plugin-cgi-perl-0.40' PERL_DL_NONLAZY=1 "/usr/bin/perl" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/00-basic.t ..................... 1..7 ok 1 - use Mojolicious::Plugin::CGI ok 2 # skip pod_file_ok(blib/lib/Mojolicious/Plugin/CGI.pm) (Test::Pod is required) -ok 3 # skip pod_coverage_ok(Mojolicious::Plugin::CGI HASH(0x58168448)) (Test::Pod::Coverage is required) +ok 3 # skip pod_coverage_ok(Mojolicious::Plugin::CGI HASH(0x2bd3c0c)) (Test::Pod::Coverage is required) ok 4 # skip changes_ok() (Test::CPAN::Changes is required) ok 5 # skip changes_ok() (Test::CPAN::Changes is required) ok 6 # skip changes_ok() (Test::CPAN::Changes is required) ok 7 # skip changes_ok() (Test::CPAN::Changes is required) ok -[2021-06-18 14:55:37.80616] [11992] [debug] GET "/working" (a46fa782) -[2021-06-18 14:55:37.80654] [11992] [debug] Routing to a callback -[2021-06-18 14:55:37.80790] [11992] [debug] [CGI:basic.pl:11993] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl -[2021-06-18 14:55:37.81562] [11992] [debug] 200 OK (0.009451s, 105.809/s) -[2021-06-18 14:55:37.81979] [11992] [debug] GET "/env/basic/foo" (07953dac) -[2021-06-18 14:55:37.82013] [11992] [debug] Routing to a callback -[2021-06-18 14:55:37.82112] [11992] [debug] [CGI:env.cgi:11994] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:37.82861] [11992] [debug] 200 OK (0.008784s, 113.843/s) -[2021-06-18 14:55:37.83683] [11992] [debug] GET "/env/basic/foo" (f5a94b69) -[2021-06-18 14:55:37.83699] [11992] [debug] Routing to a callback -[2021-06-18 14:55:37.83792] [11992] [debug] [CGI:env.cgi:11995] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:37.84537] [11992] [debug] 200 OK (0.008507s, 117.550/s) +[2020-05-17 10:37:52.69464] [27519] [debug] GET "/working" (9dd75065) +[2020-05-17 10:37:52.69535] [27519] [debug] Routing to a callback +[2020-05-17 10:37:52.69837] [27519] [debug] [CGI:basic.pl:27547] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl +[2020-05-17 10:37:52.71423] [27519] [debug] 200 OK (0.019562s, 51.120/s) +[2020-05-17 10:37:52.72099] [27519] [debug] GET "/env/basic/foo" (1a1226ba) +[2020-05-17 10:37:52.72152] [27519] [debug] Routing to a callback +[2020-05-17 10:37:52.72373] [27519] [debug] [CGI:env.cgi:27548] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:52.74523] [27519] [debug] 200 OK (0.024203s, 41.317/s) +[2020-05-17 10:37:52.76053] [27519] [debug] GET "/env/basic/foo" (3aabd54a) +[2020-05-17 10:37:52.76079] [27519] [debug] Routing to a callback +[2020-05-17 10:37:52.76347] [27519] [debug] [CGI:env.cgi:27549] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:52.78733] [27519] [debug] 200 OK (0.026755s, 37.376/s) t/basic.t ........................ ok 1 - GET /working ok 2 - 200 OK ok 3 - Content-Type: text/custom ok 4 - exact match for content -ok 5 - GET http://127.0.0.1:37419/env/basic/foo?query=123 +ok 5 - GET http://127.0.0.1:37649/env/basic/foo?query=123 ok 6 - 200 OK ok 7 - ENVIRONMENT ok 8 - CONTENT_LENGTH=0 @@ -362,10 +528,10 @@ ok 32 - HTTP_X_FORWARDED_FOR=1.2.3.4 1..32 ok -[2021-06-18 14:55:38.13216] [11996] [debug] GET "/user/42" (569f2085) -[2021-06-18 14:55:38.13252] [11996] [debug] Routing to a callback -[2021-06-18 14:55:38.13382] [11996] [debug] [CGI:env.cgi:11997] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:38.14135] [11996] [debug] 200 OK (0.009208s, 108.601/s) +[2020-05-17 10:37:53.26802] [27550] [debug] GET "/user/42" (301c461a) +[2020-05-17 10:37:53.26866] [27550] [debug] Routing to a callback +[2020-05-17 10:37:53.27148] [27550] [debug] [CGI:env.cgi:27551] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:53.29593] [27550] [debug] 200 OK (0.02788s, 35.868/s) t/before.t ....................... ok 1 - GET /user/42 ok 2 - 200 OK @@ -373,21 +539,21 @@ ok 4 - QUERY_STRING=...other_value=123 1..4 ok -[2021-06-18 14:55:38.41303] [11998] [debug] GET "/foo" (13d3c10f) -[2021-06-18 14:55:38.41336] [11998] [debug] Routing to a callback -[2021-06-18 14:55:38.41447] [11998] [debug] [CGI:CODE(0x58d365d4):11999] START CODE(0x58d365d4) +[2020-05-17 10:37:53.77993] [27552] [debug] GET "/foo" (952ab80c) +[2020-05-17 10:37:53.78044] [27552] [debug] Routing to a callback +[2020-05-17 10:37:53.78252] [27552] [debug] [CGI:CODE(0x3728790):27553] START CODE(0x3728790) # PATH_INFO=/foo -[2021-06-18 14:55:38.41834] [11998] [debug] 200 OK (0.005305s, 188.501/s) -[2021-06-18 14:55:38.42199] [11998] [debug] GET "/f%C3%B6%C3%B6" (7e6b680a) -[2021-06-18 14:55:38.42219] [11998] [debug] Routing to a callback -[2021-06-18 14:55:38.42302] [11998] [debug] [CGI:CODE(0x58d365d4):12000] START CODE(0x58d365d4) +[2020-05-17 10:37:53.79416] [27552] [debug] 200 OK (0.014219s, 70.328/s) +[2020-05-17 10:37:53.80089] [27552] [debug] GET "/f%C3%B6%C3%B6" (bbcc16e7) +[2020-05-17 10:37:53.80125] [27552] [debug] Routing to a callback +[2020-05-17 10:37:53.80330] [27552] [debug] [CGI:CODE(0x3728790):27554] START CODE(0x3728790) # PATH_INFO=/föö -[2021-06-18 14:55:38.42658] [11998] [debug] 200 OK (0.004576s, 218.531/s) -[2021-06-18 14:55:38.42993] [11998] [debug] GET "/f%C3%B6%E2%80%99" (62494ba8) -[2021-06-18 14:55:38.43017] [11998] [debug] Routing to a callback -[2021-06-18 14:55:38.43102] [11998] [debug] [CGI:CODE(0x58d365d4):12001] START CODE(0x58d365d4) +[2020-05-17 10:37:53.81353] [27552] [debug] 200 OK (0.012613s, 79.283/s) +[2020-05-17 10:37:53.81969] [27552] [debug] GET "/f%C3%B6%E2%80%99" (839ca2f5) +[2020-05-17 10:37:53.82004] [27552] [debug] Routing to a callback +[2020-05-17 10:37:53.82210] [27552] [debug] [CGI:CODE(0x3728790):27555] START CODE(0x3728790) # PATH_INFO=/fö’ -[2021-06-18 14:55:38.43457] [11998] [debug] 200 OK (0.004626s, 216.169/s) +[2020-05-17 10:37:53.83174] [27552] [debug] 200 OK (0.012028s, 83.139/s) t/encoding.t ..................... ok 1 - GET /foo ok 2 - 200 OK @@ -401,7 +567,7 @@ ok 10 - no warnings 1..10 ok -[2021-06-18 14:55:38.72683] [12002] [warn] [CGI:errlog:12003] yikes! at /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/errlog line 4. +[2020-05-17 10:37:54.35386] [27556] [warn] [CGI:errlog:27566] yikes! at /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/errlog line 4. t/errlog.t ....................... ok 1 - GET /err ok 2 - logged stderr @@ -412,19 +578,19 @@ 1..6 ok t/gh-16-loop_reset.t ............. skipped: set TEST_MORBO to enable this test (developer only!) -[2021-06-18 14:55:39.25947] [12007] [debug] GET "/cgi-bin/nope.cgi/foo" (9c5128df) -[2021-06-18 14:55:39.25982] [12007] [debug] Routing to a callback +[2020-05-17 10:37:55.33891] [27601] [debug] GET "/cgi-bin/nope.cgi/foo" (dac318a1) +[2020-05-17 10:37:55.33948] [27601] [debug] Routing to a callback Use of uninitialized value in print at t/Helper.pm line 18. -[2021-06-18 14:55:39.26120] [12007] [debug] [CGI:nope.cgi:12008] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi -[2021-06-18 14:55:39.26824] [12007] [debug] 500 Internal Server Error (0.008775s, 113.960/s) -[2021-06-18 14:55:39.27124] [12007] [debug] GET "/cgi-bin/env.cgi/some/path/info" (7c41cf04) -[2021-06-18 14:55:39.27148] [12007] [debug] Routing to a callback -[2021-06-18 14:55:39.27253] [12007] [debug] [CGI:env.cgi:12009] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:39.27932] [12007] [debug] 200 OK (0.00807s, 123.916/s) -[2021-06-18 14:55:39.28399] [12007] [debug] GET "/cgi-bin/env/some/path/info" (a0d0290e) -[2021-06-18 14:55:39.28428] [12007] [debug] Routing to a callback -[2021-06-18 14:55:39.28521] [12007] [debug] [CGI:env.cgi:12010] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:39.29189] [12007] [debug] 200 OK (0.007884s, 126.839/s) +[2020-05-17 10:37:55.34192] [27601] [debug] [CGI:nope.cgi:27656] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi +[2020-05-17 10:37:55.35542] [27601] [debug] 500 Internal Server Error (0.016487s, 60.654/s) +[2020-05-17 10:37:55.36029] [27601] [debug] GET "/cgi-bin/env.cgi/some/path/info" (7b65c688) +[2020-05-17 10:37:55.36077] [27601] [debug] Routing to a callback +[2020-05-17 10:37:55.36365] [27601] [debug] [CGI:env.cgi:27666] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:55.37754] [27601] [debug] 200 OK (0.017211s, 58.102/s) +[2020-05-17 10:37:55.38592] [27601] [debug] GET "/cgi-bin/env/some/path/info" (c148535b) +[2020-05-17 10:37:55.38644] [27601] [debug] Routing to a callback +[2020-05-17 10:37:55.38915] [27601] [debug] [CGI:env.cgi:27671] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:55.40481] [27601] [debug] 200 OK (0.018874s, 52.983/s) t/helper.t ....................... ok 1 - GET /cgi-bin/nope.cgi/foo ok 2 - 500 Internal Server Error @@ -443,50 +609,50 @@ ok 15 - SCRIPT_NAME 1..15 ok -[2021-06-18 14:55:39.57182] [12011] [debug] POST "/file_upload" (9cf21d10) -[2021-06-18 14:55:39.57215] [12011] [debug] Routing to a callback -[2021-06-18 14:55:39.57344] [12011] [debug] [CGI:file_upload:12012] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload -[2021-06-18 14:55:39.58081] [12011] [debug] 200 OK (0.00899s, 111.235/s) +[2020-05-17 10:37:55.88237] [27677] [debug] POST "/file_upload" (efa62c87) +[2020-05-17 10:37:55.88289] [27677] [debug] Routing to a callback +[2020-05-17 10:37:55.88512] [27677] [debug] [CGI:file_upload:27707] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload +[2020-05-17 10:37:55.89907] [27677] [debug] 200 OK (0.016675s, 59.970/s) t/multipart.t .................... ok 1 - POST /file_upload ok 2 - 200 OK ok 3 - content is similar 1..3 ok -[2021-06-18 14:55:39.86022] [12013] [debug] GET "/not-found" (6d7b1739) -[2021-06-18 14:55:39.86059] [12013] [debug] Routing to a callback -[2021-06-18 14:55:39.86176] [12013] [debug] [CGI:not-found.pl:12014] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl -[2021-06-18 14:55:39.86899] [12013] [debug] 404 Not Found (0.008757s, 114.194/s) +[2020-05-17 10:37:56.37161] [27708] [debug] GET "/not-found" (1e91f957) +[2020-05-17 10:37:56.37208] [27708] [debug] Routing to a callback +[2020-05-17 10:37:56.37428] [27708] [debug] [CGI:not-found.pl:27751] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl +[2020-05-17 10:37:56.38807] [27708] [debug] 404 Not Found (0.016429s, 60.868/s) t/not-found.t .................... ok 1 - GET /not-found ok 2 - 404 Not Found ok 3 - content is similar 1..3 ok -[2021-06-18 14:55:40.14989] [12015] [debug] GET "/not-modified" (91728fd5) -[2021-06-18 14:55:40.15027] [12015] [debug] Routing to a callback -[2021-06-18 14:55:40.15151] [12015] [debug] [CGI:not-modified.pl:12016] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl -[2021-06-18 14:55:40.15927] [12015] [debug] 304 Not Modified (0.009361s, 106.826/s) +[2020-05-17 10:37:56.84514] [27754] [debug] GET "/not-modified" (061a18ca) +[2020-05-17 10:37:56.84574] [27754] [debug] Routing to a callback +[2020-05-17 10:37:56.84841] [27754] [debug] [CGI:not-modified.pl:27767] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl +[2020-05-17 10:37:56.86474] [27754] [debug] 304 Not Modified (0.019579s, 51.075/s) t/not-modified.t ................. ok 1 - GET /not-modified ok 2 - 304 Not Modified ok 3 - X-Test: if-none-match seen: ABC 1..3 ok -[2021-06-18 14:55:40.43658] [12017] [debug] GET "/nph-borked" (d9e7fc30) -[2021-06-18 14:55:40.43689] [12017] [debug] Routing to a callback -[2021-06-18 14:55:40.43808] [12017] [debug] [CGI:nph-borked.pl:12018] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl -[2021-06-18 14:55:40.44540] [12017] [debug] 403 Forbidden (0.008809s, 113.520/s) +[2020-05-17 10:37:57.34215] [27768] [debug] GET "/nph-borked" (2bdb702c) +[2020-05-17 10:37:57.34259] [27768] [debug] Routing to a callback +[2020-05-17 10:37:57.34461] [27768] [debug] [CGI:nph-borked.pl:27769] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl +[2020-05-17 10:37:57.35723] [27768] [debug] 403 Forbidden (0.015036s, 66.507/s) t/nph-borked.t ................... ok 1 - GET /nph-borked ok 2 - 403 Forbidden ok 3 - content is similar 1..3 ok -[2021-06-18 14:55:40.72273] [12019] [debug] GET "/nph" (08cce59f) -[2021-06-18 14:55:40.72307] [12019] [debug] Routing to a callback -[2021-06-18 14:55:40.72430] [12019] [debug] [CGI:nph.pl:12020] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl -[2021-06-18 14:55:40.73179] [12019] [debug] 403 Payment Required (0.00905s, 110.497/s) +[2020-05-17 10:37:57.82445] [27770] [debug] GET "/nph" (b83cfd99) +[2020-05-17 10:37:57.82490] [27770] [debug] Routing to a callback +[2020-05-17 10:37:57.82695] [27770] [debug] [CGI:nph.pl:27789] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl +[2020-05-17 10:37:57.84242] [27770] [debug] 403 Payment Required (0.017942s, 55.735/s) t/nph.t .......................... ok 1 - GET /nph ok 2 - 403 Forbidden @@ -494,10 +660,10 @@ 1..3 ok t/post.t ......................... skipped: TEST_PIPES=1; No idea how to test this consistently -[2021-06-18 14:55:41.26834] [12022] [debug] GET "/env/basic/foo" (43498d84) -[2021-06-18 14:55:41.26867] [12022] [debug] Routing to a callback -[2021-06-18 14:55:41.26977] [12022] [debug] [CGI:env.cgi:12023] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:41.27642] [12022] [debug] 200 OK (0.008076s, 123.824/s) +[2020-05-17 10:37:58.79416] [27800] [debug] GET "/env/basic/foo" (a8bb7ef7) +[2020-05-17 10:37:58.79464] [27800] [debug] Routing to a callback +[2020-05-17 10:37:58.79688] [27800] [debug] [CGI:env.cgi:27837] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:58.81320] [27800] [debug] 200 OK (0.019011s, 52.601/s) t/query-string-with-semicolon.t .. ok 1 - GET /env/basic/foo?a=1;b=2 ok 2 - 200 OK @@ -509,10 +675,10 @@ # expected: '1' 1..4 ok -[2021-06-18 14:55:41.55154] [12024] [debug] GET "/redirect" (0c9834af) -[2021-06-18 14:55:41.55189] [12024] [debug] Routing to a callback -[2021-06-18 14:55:41.55307] [12024] [debug] [CGI:redirect.pl:12025] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl -[2021-06-18 14:55:41.56064] [12024] [debug] 302 Found (0.009094s, 109.963/s) +[2020-05-17 10:37:59.29041] [27847] [debug] GET "/redirect" (cf59528d) +[2020-05-17 10:37:59.29094] [27847] [debug] Routing to a callback +[2020-05-17 10:37:59.29316] [27847] [debug] [CGI:redirect.pl:27897] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl +[2020-05-17 10:37:59.31017] [27847] [debug] 302 Found (0.019745s, 50.646/s) t/redirect.t ..................... ok 1 - GET /redirect ok 2 - 302 Found @@ -520,35 +686,35 @@ ok 4 - exact match for content 1..4 ok -[2021-06-18 14:55:41.84725] [12026] [debug] GET "/auth" (f7cc41f2) -[2021-06-18 14:55:41.84756] [12026] [debug] Routing to a callback -[2021-06-18 14:55:41.84876] [12026] [debug] [CGI:env.cgi:12027] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:41.85572] [12026] [debug] 200 OK (0.008462s, 118.175/s) -[2021-06-18 14:55:41.85943] [12026] [debug] GET "/auth" (ca5cb91f) -[2021-06-18 14:55:41.85958] [12026] [debug] Routing to a callback -[2021-06-18 14:55:41.86041] [12026] [debug] [CGI:env.cgi:12028] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:41.86693] [12026] [debug] 200 OK (0.007477s, 133.743/s) -[2021-06-18 14:55:41.87054] [12026] [debug] GET "/auth" (d8c1c7fc) -[2021-06-18 14:55:41.87070] [12026] [debug] Routing to a callback -[2021-06-18 14:55:41.87157] [12026] [debug] [CGI:env.cgi:12029] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2021-06-18 14:55:41.87838] [12026] [debug] 200 OK (0.007808s, 128.074/s) +[2020-05-17 10:37:59.78224] [27898] [debug] GET "/auth" (8df67981) +[2020-05-17 10:37:59.78295] [27898] [debug] Routing to a callback +[2020-05-17 10:37:59.78612] [27898] [debug] [CGI:env.cgi:27909] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:59.79900] [27898] [debug] 200 OK (0.016728s, 59.780/s) +[2020-05-17 10:37:59.80664] [27898] [debug] GET "/auth" (175dfcf7) +[2020-05-17 10:37:59.80696] [27898] [debug] Routing to a callback +[2020-05-17 10:37:59.81006] [27898] [debug] [CGI:env.cgi:27910] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:59.82576] [27898] [debug] 200 OK (0.019092s, 52.378/s) +[2020-05-17 10:37:59.83311] [27898] [debug] GET "/auth" (2dacf392) +[2020-05-17 10:37:59.83339] [27898] [debug] Routing to a callback +[2020-05-17 10:37:59.83647] [27898] [debug] [CGI:env.cgi:27911] START /build/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2020-05-17 10:37:59.84945] [27898] [debug] 200 OK (0.016297s, 61.361/s) t/remote-user.t .................. ok 1 - GET /auth ok 2 - 200 OK ok 3 - 200 OK ok 4 - REMOTE_USER= -ok 5 - GET http://127.0.0.1:46821/auth +ok 5 - GET http://127.0.0.1:45751/auth ok 6 - 200 OK ok 7 - REMOTE_USER=Aladdin -ok 8 - GET http://127.0.0.1:46821/auth +ok 8 - GET http://127.0.0.1:45751/auth ok 9 - 200 OK ok 10 - REMOTE_USER=Aladdin 1..10 ok -[2021-06-18 14:55:42.15242] [12030] [debug] GET "/" (d284ac89) -[2021-06-18 14:55:42.15271] [12030] [debug] Routing to a callback -[2021-06-18 14:55:42.15374] [12030] [debug] [CGI:CODE(0x57cd13a8):12031] START CODE(0x57cd13a8) -[2021-06-18 14:55:42.15692] [12030] [debug] 200 OK (0.004481s, 223.164/s) +[2020-05-17 10:38:00.35566] [27920] [debug] GET "/" (318f37bc) +[2020-05-17 10:38:00.35610] [27920] [debug] Routing to a callback +[2020-05-17 10:38:00.35836] [27920] [debug] [CGI:CODE(0xbd33f8):27953] START CODE(0xbd33f8) +[2020-05-17 10:38:00.36692] [27920] [debug] 200 OK (0.011236s, 89.000/s) t/run.t .......................... ok 1 - GET / ok 2 - 200 OK @@ -557,7 +723,7 @@ ok t/zombies.t ...................... skipped: (no reason given) All tests successful. -Files=18, Tests=110, 5 wallclock secs ( 0.06 usr 0.02 sys + 4.56 cusr 0.46 csys = 5.10 CPU) +Files=18, Tests=110, 9 wallclock secs ( 0.11 usr 0.02 sys + 7.82 cusr 0.96 csys = 8.91 CPU) Result: PASS make[1]: Leaving directory '/build/libmojolicious-plugin-cgi-perl-0.40' create-stamp debian/debhelper-build-stamp @@ -566,7 +732,7 @@ dh_testroot dh_prep dh_auto_install - make -j18 install DESTDIR=/build/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl AM_UPDATE_INFO_DIR=no PREFIX=/usr + make -j10 install DESTDIR=/build/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl AM_UPDATE_INFO_DIR=no PREFIX=/usr make[1]: Entering directory '/build/libmojolicious-plugin-cgi-perl-0.40' Manifying 1 pod document Installing /build/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl/usr/share/perl5/Mojolicious/Plugin/CGI.pm @@ -592,12 +758,14 @@ dpkg-source --after-build . dpkg-buildpackage: info: binary-only upload (no source included) I: copying local configuration +I: user script /srv/workspace/pbuilder/24445/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/24445/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/9891 and its subdirectories -I: Current time: Fri Jun 18 14:55:44 -12 2021 -I: pbuilder-time-stamp: 1624071344 +I: removing directory /srv/workspace/pbuilder/24445 and its subdirectories +I: Current time: Sun May 17 10:38:06 +14 2020 +I: pbuilder-time-stamp: 1589661486