Diff of the two buildlogs: -- --- b1/build.log 2024-07-15 12:54:09.378738853 +0000 +++ b2/build.log 2024-07-15 12:57:17.078763074 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Jul 15 00:52:37 -12 2024 -I: pbuilder-time-stamp: 1721047957 +I: Current time: Tue Jul 16 02:54:21 +14 2024 +I: pbuilder-time-stamp: 1721048061 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -25,52 +25,84 @@ dpkg-source: info: unpacking libmojolicious-plugin-cgi-perl_0.40-2.debian.tar.xz I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/7289/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/25970/tmp/hooks/D01_modify_environment starting +debug: Running on cbxi4a. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jul 15 12:54 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/25970/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/25970/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='a32f2c6e18cb47239ed9f3c7cb806057' - 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='7289' - PS1='# ' - PS2='> ' + INVOCATION_ID=14b3e312e03a4bc0bf8470dda56d7b0c + 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=25970 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.ppHeHrkN/pbuilderrc_YXxg --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ppHeHrkN/b1 --logfile b1/build.log libmojolicious-plugin-cgi-perl_0.40-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:3142/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.ppHeHrkN/pbuilderrc_CTPI --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ppHeHrkN/b2 --logfile b2/build.log libmojolicious-plugin-cgi-perl_0.40-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:3142/ I: uname -a - Linux virt64b 6.1.0-22-arm64 #1 SMP Debian 6.1.94-1 (2024-06-21) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-22-armmp #1 SMP Debian 6.1.94-1 (2024-06-21) armv7l GNU/Linux I: ls -l /bin lrwxrwxrwx 1 root root 7 Jun 5 22:35 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/7289/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/25970/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -145,7 +177,7 @@ Get: 33 http://deb.debian.org/debian trixie/main armhf libjs-bootstrap4 all 4.6.1+dfsg1-4 [517 kB] Get: 34 http://deb.debian.org/debian trixie/main armhf libjs-highlight.js all 9.18.5+dfsg1-2 [397 kB] Get: 35 http://deb.debian.org/debian trixie/main armhf libmojolicious-perl all 9.37+dfsg-1 [843 kB] -Fetched 20.7 MB in 1s (23.0 MB/s) +Fetched 20.7 MB in 2s (11.0 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19407 files and directories currently installed.) @@ -300,7 +332,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/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 && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../libmojolicious-plugin-cgi-perl_0.40-2_source.changes +I: user script /srv/workspace/pbuilder/25970/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/25970/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/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 && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../libmojolicious-plugin-cgi-perl_0.40-2_source.changes dpkg-buildpackage: info: source package libmojolicious-plugin-cgi-perl dpkg-buildpackage: info: source version 0.40-2 dpkg-buildpackage: info: source distribution unstable @@ -322,43 +358,43 @@ Writing Makefile for Mojolicious::Plugin::CGI Writing MYMETA.yml and MYMETA.json dh_auto_build - make -j3 + make -j4 make[1]: Entering directory '/build/reproducible-path/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/reproducible-path/libmojolicious-plugin-cgi-perl-0.40' dh_auto_test - make -j3 test TEST_VERBOSE=1 + make -j4 test TEST_VERBOSE=1 make[1]: Entering directory '/build/reproducible-path/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(0x20b84d0)) (Test::Pod::Coverage is required) +ok 3 # skip pod_coverage_ok(Mojolicious::Plugin::CGI HASH(0x2aff6d8)) (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 -[2024-07-15 12:53:39.67502] [12646] [trace] [P9QQUs4a22g1] GET "/working" -[2024-07-15 12:53:39.67602] [12646] [trace] [P9QQUs4a22g1] Routing to a callback -[2024-07-15 12:53:39.68375] [12646] [debug] [CGI:basic.pl:12749] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl -[2024-07-15 12:53:39.73999] [12646] [trace] [P9QQUs4a22g1] 200 OK (0.064936s, 15.400/s) -[2024-07-15 12:53:39.76752] [12646] [trace] [1zwPILXcfW1f] GET "/env/basic/foo" -[2024-07-15 12:53:39.76852] [12646] [trace] [1zwPILXcfW1f] Routing to a callback -[2024-07-15 12:53:39.77220] [12646] [debug] [CGI:env.cgi:12751] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:39.79996] [12646] [trace] [1zwPILXcfW1f] 200 OK (0.032384s, 30.879/s) -[2024-07-15 12:53:39.82761] [12646] [trace] [bmSuDDJeoOr8] GET "/env/basic/foo" -[2024-07-15 12:53:39.82811] [12646] [trace] [bmSuDDJeoOr8] Routing to a callback -[2024-07-15 12:53:39.83140] [12646] [debug] [CGI:env.cgi:12754] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:39.85401] [12646] [trace] [bmSuDDJeoOr8] 200 OK (0.026343s, 37.961/s) +[2024-07-15 12:56:06.55631] [27090] [trace] [4u1VD_Mq4czJ] GET "/working" +[2024-07-15 12:56:06.55881] [27090] [trace] [4u1VD_Mq4czJ] Routing to a callback +[2024-07-15 12:56:06.56838] [27090] [debug] [CGI:basic.pl:27091] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl +[2024-07-15 12:56:06.63907] [27090] [trace] [4u1VD_Mq4czJ] 200 OK (0.082715s, 12.090/s) +[2024-07-15 12:56:06.67250] [27090] [trace] [elZuhPKyJYxK] GET "/env/basic/foo" +[2024-07-15 12:56:06.67486] [27090] [trace] [elZuhPKyJYxK] Routing to a callback +[2024-07-15 12:56:06.68340] [27090] [debug] [CGI:env.cgi:27092] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:06.75632] [27090] [trace] [elZuhPKyJYxK] 200 OK (0.083726s, 11.944/s) +[2024-07-15 12:56:06.82870] [27090] [trace] [jHpgeqPUay98] GET "/env/basic/foo" +[2024-07-15 12:56:06.82997] [27090] [trace] [jHpgeqPUay98] Routing to a callback +[2024-07-15 12:56:06.83862] [27090] [debug] [CGI:env.cgi:27093] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:06.91023] [27090] [trace] [jHpgeqPUay98] 200 OK (0.081434s, 12.280/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:42967/env/basic/foo?query=123 +ok 5 - GET http://127.0.0.1:43107/env/basic/foo?query=123 ok 6 - 200 OK ok 7 - ENVIRONMENT ok 8 - CONTENT_LENGTH=0 @@ -388,10 +424,10 @@ ok 32 - HTTP_X_FORWARDED_FOR=1.2.3.4 1..32 ok -[2024-07-15 12:53:41.07558] [12764] [trace] [PopG85happ8l] GET "/user/42" -[2024-07-15 12:53:41.07661] [12764] [trace] [PopG85happ8l] Routing to a callback -[2024-07-15 12:53:41.08110] [12764] [debug] [CGI:env.cgi:12865] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:41.10820] [12764] [trace] [PopG85happ8l] 200 OK (0.032598s, 30.677/s) +[2024-07-15 12:56:09.71434] [27094] [trace] [1MAeSnGmorYn] GET "/user/42" +[2024-07-15 12:56:09.71697] [27094] [trace] [1MAeSnGmorYn] Routing to a callback +[2024-07-15 12:56:09.72664] [27094] [debug] [CGI:env.cgi:27095] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:09.79870] [27094] [trace] [1MAeSnGmorYn] 200 OK (0.084316s, 11.860/s) t/before.t ....................... ok 1 - GET /user/42 ok 2 - 200 OK @@ -399,21 +435,21 @@ ok 4 - QUERY_STRING=...other_value=123 1..4 ok -[2024-07-15 12:53:42.34104] [12870] [trace] [xv-bQwAxexBi] GET "/foo" -[2024-07-15 12:53:42.34218] [12870] [trace] [xv-bQwAxexBi] Routing to a callback -[2024-07-15 12:53:42.34649] [12870] [debug] [CGI:CODE(0x26ea3c8):12993] START CODE(0x26ea3c8) +[2024-07-15 12:56:12.66553] [27096] [trace] [iPQhzUkNnt3L] GET "/foo" +[2024-07-15 12:56:12.66815] [27096] [trace] [iPQhzUkNnt3L] Routing to a callback +[2024-07-15 12:56:12.67763] [27096] [debug] [CGI:CODE(0x21c7a88):27098] START CODE(0x21c7a88) # PATH_INFO=/foo -[2024-07-15 12:53:42.36843] [12870] [trace] [xv-bQwAxexBi] 200 OK (0.027352s, 36.560/s) -[2024-07-15 12:53:42.40808] [12870] [trace] [MS-6ROAf2PCn] GET "/f%C3%B6%C3%B6" -[2024-07-15 12:53:42.40893] [12870] [trace] [MS-6ROAf2PCn] Routing to a callback -[2024-07-15 12:53:42.41654] [12870] [debug] [CGI:CODE(0x26ea3c8):12998] START CODE(0x26ea3c8) +[2024-07-15 12:56:12.71539] [27096] [trace] [iPQhzUkNnt3L] 200 OK (0.049712s, 20.116/s) +[2024-07-15 12:56:12.74648] [27096] [trace] [DPXUD2CfSdif] GET "/f%C3%B6%C3%B6" +[2024-07-15 12:56:12.74847] [27096] [trace] [DPXUD2CfSdif] Routing to a callback +[2024-07-15 12:56:12.75686] [27096] [debug] [CGI:CODE(0x21c7a88):27099] START CODE(0x21c7a88) # PATH_INFO=/föö -[2024-07-15 12:53:42.45630] [12870] [trace] [MS-6ROAf2PCn] 200 OK (0.048163s, 20.763/s) -[2024-07-15 12:53:42.48156] [12870] [trace] [EMAss76eELPi] GET "/f%C3%B6%E2%80%99" -[2024-07-15 12:53:42.48740] [12870] [trace] [EMAss76eELPi] Routing to a callback -[2024-07-15 12:53:42.49052] [12870] [debug] [CGI:CODE(0x26ea3c8):12999] START CODE(0x26ea3c8) +[2024-07-15 12:56:12.79456] [27096] [trace] [DPXUD2CfSdif] 200 OK (0.047841s, 20.903/s) +[2024-07-15 12:56:12.82487] [27096] [trace] [mgRYM11d80zm] GET "/f%C3%B6%E2%80%99" +[2024-07-15 12:56:12.82677] [27096] [trace] [mgRYM11d80zm] Routing to a callback +[2024-07-15 12:56:12.83544] [27096] [debug] [CGI:CODE(0x21c7a88):27100] START CODE(0x21c7a88) # PATH_INFO=/fö’ -[2024-07-15 12:53:42.52002] [12870] [trace] [EMAss76eELPi] 200 OK (0.038408s, 26.036/s) +[2024-07-15 12:56:12.87321] [27096] [trace] [mgRYM11d80zm] 200 OK (0.048098s, 20.791/s) t/encoding.t ..................... ok 1 - GET /foo ok 2 - 200 OK @@ -427,7 +463,7 @@ ok 10 - no warnings 1..10 ok -[2024-07-15 12:53:44.07638] [13001] [warn] [CGI:errlog:13115] yikes! at /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/errlog line 4. +[2024-07-15 12:56:15.73536] [27101] [warn] [CGI:errlog:27102] yikes! at /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/errlog line 4. t/errlog.t ....................... ok 1 - GET /err ok 2 - logged stderr @@ -438,19 +474,19 @@ 1..6 ok t/gh-16-loop_reset.t ............. skipped: set TEST_MORBO to enable this test (developer only!) -[2024-07-15 12:53:46.13470] [13241] [trace] [6MuaNx-dizYG] GET "/cgi-bin/nope.cgi/foo" -[2024-07-15 12:53:46.13572] [13241] [trace] [6MuaNx-dizYG] Routing to a callback +[2024-07-15 12:56:21.17624] [27106] [trace] [7P_UfnYcbzP7] GET "/cgi-bin/nope.cgi/foo" +[2024-07-15 12:56:21.17905] [27106] [trace] [7P_UfnYcbzP7] Routing to a callback Use of uninitialized value in print at t/Helper.pm line 18. -[2024-07-15 12:53:46.14049] [13241] [debug] [CGI:nope.cgi:13356] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi -[2024-07-15 12:53:46.16196] [13241] [trace] [6MuaNx-dizYG] 500 Internal Server Error (0.02725s, 36.697/s) -[2024-07-15 12:53:46.17358] [13241] [trace] [h9_6P-DjcL8p] GET "/cgi-bin/env.cgi/some/path/info" -[2024-07-15 12:53:46.17436] [13241] [trace] [h9_6P-DjcL8p] Routing to a callback -[2024-07-15 12:53:46.17889] [13241] [debug] [CGI:env.cgi:13361] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:46.20399] [13241] [trace] [h9_6P-DjcL8p] 200 OK (0.030347s, 32.952/s) -[2024-07-15 12:53:46.22083] [13241] [trace] [NynsUYyddidF] GET "/cgi-bin/env/some/path/info" -[2024-07-15 12:53:46.22170] [13241] [trace] [NynsUYyddidF] Routing to a callback -[2024-07-15 12:53:46.22614] [13241] [debug] [CGI:env.cgi:13370] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:46.25159] [13241] [trace] [NynsUYyddidF] 200 OK (0.030703s, 32.570/s) +[2024-07-15 12:56:21.19076] [27106] [debug] [CGI:nope.cgi:27107] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi +[2024-07-15 12:56:21.25796] [27106] [trace] [7P_UfnYcbzP7] 500 Internal Server Error (0.08164s, 12.249/s) +[2024-07-15 12:56:21.28260] [27106] [trace] [2BooWfPsArtk] GET "/cgi-bin/env.cgi/some/path/info" +[2024-07-15 12:56:21.28473] [27106] [trace] [2BooWfPsArtk] Routing to a callback +[2024-07-15 12:56:21.29462] [27106] [debug] [CGI:env.cgi:27108] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:21.36210] [27106] [trace] [2BooWfPsArtk] 200 OK (0.079414s, 12.592/s) +[2024-07-15 12:56:21.39783] [27106] [trace] [fbcRjOiFMrmB] GET "/cgi-bin/env/some/path/info" +[2024-07-15 12:56:21.39993] [27106] [trace] [fbcRjOiFMrmB] Routing to a callback +[2024-07-15 12:56:21.40980] [27106] [debug] [CGI:env.cgi:27109] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:21.47725] [27106] [trace] [fbcRjOiFMrmB] 200 OK (0.079324s, 12.607/s) t/helper.t ....................... ok 1 - GET /cgi-bin/nope.cgi/foo ok 2 - 500 Internal Server Error @@ -469,50 +505,50 @@ ok 15 - SCRIPT_NAME 1..15 ok -[2024-07-15 12:53:47.27804] [13371] [trace] [hF-hhbhLPpuN] POST "/file_upload" -[2024-07-15 12:53:47.27952] [13371] [trace] [hF-hhbhLPpuN] Routing to a callback -[2024-07-15 12:53:47.28451] [13371] [debug] [CGI:file_upload:13494] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload -[2024-07-15 12:53:47.31196] [13371] [trace] [hF-hhbhLPpuN] 200 OK (0.033899s, 29.499/s) +[2024-07-15 12:56:24.33025] [27110] [trace] [6Rv7fQIAMx0i] POST "/file_upload" +[2024-07-15 12:56:24.33284] [27110] [trace] [6Rv7fQIAMx0i] Routing to a callback +[2024-07-15 12:56:24.34380] [27110] [debug] [CGI:file_upload:27112] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload +[2024-07-15 12:56:24.41594] [27110] [trace] [6Rv7fQIAMx0i] 200 OK (0.085639s, 11.677/s) t/multipart.t .................... ok 1 - POST /file_upload ok 2 - 200 OK ok 3 - content is similar 1..3 ok -[2024-07-15 12:53:48.30450] [13500] [trace] [jo90qxt2cgc8] GET "/not-found" -[2024-07-15 12:53:48.30548] [13500] [trace] [jo90qxt2cgc8] Routing to a callback -[2024-07-15 12:53:48.30959] [13500] [debug] [CGI:not-found.pl:13605] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl -[2024-07-15 12:53:48.33505] [13500] [trace] [jo90qxt2cgc8] 404 Not Found (0.03053s, 32.755/s) +[2024-07-15 12:56:27.23053] [27113] [trace] [wiAMiVY9YWVo] GET "/not-found" +[2024-07-15 12:56:27.23314] [27113] [trace] [wiAMiVY9YWVo] Routing to a callback +[2024-07-15 12:56:27.24256] [27113] [debug] [CGI:not-found.pl:27115] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl +[2024-07-15 12:56:27.31383] [27113] [trace] [wiAMiVY9YWVo] 404 Not Found (0.083269s, 12.009/s) t/not-found.t .................... ok 1 - GET /not-found ok 2 - 404 Not Found ok 3 - content is similar 1..3 ok -[2024-07-15 12:53:49.50468] [13615] [trace] [zy8FstjQXz7-] GET "/not-modified" -[2024-07-15 12:53:49.50571] [13615] [trace] [zy8FstjQXz7-] Routing to a callback -[2024-07-15 12:53:49.50989] [13615] [debug] [CGI:not-modified.pl:13806] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl -[2024-07-15 12:53:49.55186] [13615] [trace] [zy8FstjQXz7-] 304 Not Modified (0.047135s, 21.216/s) +[2024-07-15 12:56:30.19689] [27116] [trace] [yntthC-cDJoB] GET "/not-modified" +[2024-07-15 12:56:30.19938] [27116] [trace] [yntthC-cDJoB] Routing to a callback +[2024-07-15 12:56:30.20916] [27116] [debug] [CGI:not-modified.pl:27117] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl +[2024-07-15 12:56:30.28095] [27116] [trace] [yntthC-cDJoB] 304 Not Modified (0.084002s, 11.904/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 -[2024-07-15 12:53:50.73602] [13818] [trace] [8aQyTRdUcIBS] GET "/nph-borked" -[2024-07-15 12:53:50.73700] [13818] [trace] [8aQyTRdUcIBS] Routing to a callback -[2024-07-15 12:53:50.74104] [13818] [debug] [CGI:nph-borked.pl:13984] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl -[2024-07-15 12:53:50.76788] [13818] [trace] [8aQyTRdUcIBS] 403 Forbidden (0.031819s, 31.428/s) +[2024-07-15 12:56:33.08110] [27118] [trace] [-6o2H5rT3VSe] GET "/nph-borked" +[2024-07-15 12:56:33.08368] [27118] [trace] [-6o2H5rT3VSe] Routing to a callback +[2024-07-15 12:56:33.09336] [27118] [debug] [CGI:nph-borked.pl:27119] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl +[2024-07-15 12:56:33.16345] [27118] [trace] [-6o2H5rT3VSe] 403 Forbidden (0.082304s, 12.150/s) t/nph-borked.t ................... ok 1 - GET /nph-borked ok 2 - 403 Forbidden ok 3 - content is similar 1..3 ok -[2024-07-15 12:53:51.92366] [13994] [trace] [izKrxP7A6kZ6] GET "/nph" -[2024-07-15 12:53:51.92470] [13994] [trace] [izKrxP7A6kZ6] Routing to a callback -[2024-07-15 12:53:51.92923] [13994] [debug] [CGI:nph.pl:14140] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl -[2024-07-15 12:53:51.95989] [13994] [trace] [izKrxP7A6kZ6] 403 Payment Required (0.036195s, 27.628/s) +[2024-07-15 12:56:36.00757] [27120] [trace] [Fn6CcDtPj-Ks] GET "/nph" +[2024-07-15 12:56:36.01009] [27120] [trace] [Fn6CcDtPj-Ks] Routing to a callback +[2024-07-15 12:56:36.01967] [27120] [debug] [CGI:nph.pl:27122] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl +[2024-07-15 12:56:36.09035] [27120] [trace] [Fn6CcDtPj-Ks] 403 Payment Required (0.082822s, 12.074/s) t/nph.t .......................... ok 1 - GET /nph ok 2 - 403 Forbidden @@ -520,10 +556,10 @@ 1..3 ok t/post.t ......................... skipped: TEST_PIPES=1; No idea how to test this consistently -[2024-07-15 12:53:54.16579] [14286] [trace] [nVH1LLipGZjQ] GET "/env/basic/foo" -[2024-07-15 12:53:54.16741] [14286] [trace] [nVH1LLipGZjQ] Routing to a callback -[2024-07-15 12:53:54.17144] [14286] [debug] [CGI:env.cgi:14428] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:54.19374] [14286] [trace] [nVH1LLipGZjQ] 200 OK (0.027918s, 35.819/s) +[2024-07-15 12:56:41.64996] [27124] [trace] [ICxL8QN53TwX] GET "/env/basic/foo" +[2024-07-15 12:56:41.65337] [27124] [trace] [ICxL8QN53TwX] Routing to a callback +[2024-07-15 12:56:41.66515] [27124] [debug] [CGI:env.cgi:27125] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:41.73313] [27124] [trace] [ICxL8QN53TwX] 200 OK (0.083146s, 12.027/s) t/query-string-with-semicolon.t .. ok 1 - GET /env/basic/foo?a=1;b=2 ok 2 - 200 OK @@ -535,10 +571,10 @@ # expected: '1' 1..4 ok -[2024-07-15 12:53:55.18366] [14432] [trace] [tZSZUHfMQJ4J] GET "/redirect" -[2024-07-15 12:53:55.18459] [14432] [trace] [tZSZUHfMQJ4J] Routing to a callback -[2024-07-15 12:53:55.18872] [14432] [debug] [CGI:redirect.pl:14537] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl -[2024-07-15 12:53:55.21455] [14432] [trace] [tZSZUHfMQJ4J] 302 Found (0.030865s, 32.399/s) +[2024-07-15 12:56:44.53738] [27126] [trace] [ragcfLmeczAm] GET "/redirect" +[2024-07-15 12:56:44.53999] [27126] [trace] [ragcfLmeczAm] Routing to a callback +[2024-07-15 12:56:44.54954] [27126] [debug] [CGI:redirect.pl:27127] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl +[2024-07-15 12:56:44.62153] [27126] [trace] [ragcfLmeczAm] 302 Found (0.084129s, 11.887/s) t/redirect.t ..................... ok 1 - GET /redirect ok 2 - 302 Found @@ -546,35 +582,35 @@ ok 4 - exact match for content 1..4 ok -[2024-07-15 12:53:56.19280] [14538] [trace] [ybQkkJgeze3k] GET "/auth" -[2024-07-15 12:53:56.19380] [14538] [trace] [ybQkkJgeze3k] Routing to a callback -[2024-07-15 12:53:56.19806] [14538] [debug] [CGI:env.cgi:14605] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:56.22285] [14538] [trace] [ybQkkJgeze3k] 200 OK (0.030031s, 33.299/s) -[2024-07-15 12:53:56.23722] [14538] [trace] [Ijll1Sxz7bJl] GET "/auth" -[2024-07-15 12:53:56.23772] [14538] [trace] [Ijll1Sxz7bJl] Routing to a callback -[2024-07-15 12:53:56.24147] [14538] [debug] [CGI:env.cgi:14606] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:56.26624] [14538] [trace] [Ijll1Sxz7bJl] 200 OK (0.028965s, 34.524/s) -[2024-07-15 12:53:56.28019] [14538] [trace] [wlJC_vdlVtG8] GET "/auth" -[2024-07-15 12:53:56.28070] [14538] [trace] [wlJC_vdlVtG8] Routing to a callback -[2024-07-15 12:53:56.28441] [14538] [debug] [CGI:env.cgi:14612] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-07-15 12:53:56.30917] [14538] [trace] [wlJC_vdlVtG8] 200 OK (0.028889s, 34.615/s) +[2024-07-15 12:56:47.45438] [27128] [trace] [uZisOB0RDGNY] GET "/auth" +[2024-07-15 12:56:47.45687] [27128] [trace] [uZisOB0RDGNY] Routing to a callback +[2024-07-15 12:56:47.46624] [27128] [debug] [CGI:env.cgi:27130] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:47.53417] [27128] [trace] [uZisOB0RDGNY] 200 OK (0.079746s, 12.540/s) +[2024-07-15 12:56:47.56631] [27128] [trace] [doeSMCNdaF2X] GET "/auth" +[2024-07-15 12:56:47.56763] [27128] [trace] [doeSMCNdaF2X] Routing to a callback +[2024-07-15 12:56:47.57634] [27128] [debug] [CGI:env.cgi:27131] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:47.64470] [27128] [trace] [doeSMCNdaF2X] 200 OK (0.078283s, 12.774/s) +[2024-07-15 12:56:47.67585] [27128] [trace] [ly615mO67kSF] GET "/auth" +[2024-07-15 12:56:47.67712] [27128] [trace] [ly615mO67kSF] Routing to a callback +[2024-07-15 12:56:47.68590] [27128] [debug] [CGI:env.cgi:27132] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2024-07-15 12:56:47.75390] [27128] [trace] [ly615mO67kSF] 200 OK (0.077958s, 12.827/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:36271/auth +ok 5 - GET http://127.0.0.1:33619/auth ok 6 - 200 OK ok 7 - REMOTE_USER=Aladdin -ok 8 - GET http://127.0.0.1:36271/auth +ok 8 - GET http://127.0.0.1:33619/auth ok 9 - 200 OK ok 10 - REMOTE_USER=Aladdin 1..10 ok -[2024-07-15 12:53:57.43907] [14616] [trace] [kh0A97DuavnA] GET "/" -[2024-07-15 12:53:57.43999] [14616] [trace] [kh0A97DuavnA] Routing to a callback -[2024-07-15 12:53:57.44400] [14616] [debug] [CGI:CODE(0x10dde48):14686] START CODE(0x10dde48) -[2024-07-15 12:53:57.45618] [14616] [trace] [kh0A97DuavnA] 200 OK (0.017066s, 58.596/s) +[2024-07-15 12:56:50.53929] [27133] [trace] [28515FFz0ll4] GET "/" +[2024-07-15 12:56:50.54181] [27133] [trace] [28515FFz0ll4] Routing to a callback +[2024-07-15 12:56:50.55114] [27133] [debug] [CGI:CODE(0x2402ef0):27134] START CODE(0x2402ef0) +[2024-07-15 12:56:50.58189] [27133] [trace] [28515FFz0ll4] 200 OK (0.042501s, 23.529/s) t/run.t .......................... ok 1 - GET / ok 2 - 200 OK @@ -583,13 +619,13 @@ ok t/zombies.t ...................... skipped: (no reason given) All tests successful. -Files=18, Tests=110, 21 wallclock secs ( 0.16 usr 0.04 sys + 17.04 cusr 1.62 csys = 18.86 CPU) +Files=18, Tests=110, 50 wallclock secs ( 0.31 usr 0.18 sys + 45.68 cusr 5.11 csys = 51.28 CPU) Result: PASS make[1]: Leaving directory '/build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40' create-stamp debian/debhelper-build-stamp dh_prep dh_auto_install --destdir=debian/libmojolicious-plugin-cgi-perl/ - make -j3 install DESTDIR=/build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl AM_UPDATE_INFO_DIR=no PREFIX=/usr + make -j4 install DESTDIR=/build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl AM_UPDATE_INFO_DIR=no PREFIX=/usr make[1]: Entering directory '/build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40' Manifying 1 pod document Installing /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl/usr/share/perl5/Mojolicious/Plugin/CGI.pm @@ -616,12 +652,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/25970/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/25970/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/7289 and its subdirectories -I: Current time: Mon Jul 15 00:54:05 -12 2024 -I: pbuilder-time-stamp: 1721048045 +I: removing directory /srv/workspace/pbuilder/25970 and its subdirectories +I: Current time: Tue Jul 16 02:57:12 +14 2024 +I: pbuilder-time-stamp: 1721048232