Diff of the two buildlogs: -- --- b1/build.log 2024-05-19 17:17:18.533166994 +0000 +++ b2/build.log 2024-05-19 17:17:50.019826925 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun May 19 05:16:03 -12 2024 -I: pbuilder-time-stamp: 1716138963 +I: Current time: Sun Jun 22 13:40:19 +14 2025 +I: pbuilder-time-stamp: 1750549219 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -26,54 +26,86 @@ 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/44159/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/90302/tmp/hooks/D01_modify_environment starting +debug: Running on ionos16-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 +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jun 21 23:40 /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/90302/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/90302/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='i386' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=11 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='i386' + 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]="i686-pc-linux-gnu") + 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=i386 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=21 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='04f9a03d14f14ab2a7619a35002a701b' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - LD_LIBRARY_PATH='/usr/lib/libeatmydata' - LD_PRELOAD='libeatmydata.so' - 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='44159' - PS1='# ' - PS2='> ' + INVOCATION_ID=e6154f4be9fc4cc083b0cf2d348fe7d2 + 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=90302 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.37zdOZN2/pbuilderrc_8Du7 --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.37zdOZN2/b1 --logfile b1/build.log libmojolicious-plugin-cgi-perl_0.40-2.dsc' - SUDO_GID='112' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://46.16.76.132:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.37zdOZN2/pbuilderrc_heeB --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.37zdOZN2/b2 --logfile b2/build.log libmojolicious-plugin-cgi-perl_0.40-2.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://213.165.73.152:3128 I: uname -a - Linux ionos2-i386 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 18 07:44 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/44159/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 20 14:06 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/90302/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -154,7 +186,7 @@ Get: 39 http://deb.debian.org/debian unstable/main i386 libjs-bootstrap4 all 4.6.1+dfsg1-4 [517 kB] Get: 40 http://deb.debian.org/debian unstable/main i386 libjs-highlight.js all 9.18.5+dfsg1-2 [397 kB] Get: 41 http://deb.debian.org/debian unstable/main i386 libmojolicious-perl all 9.37+dfsg-1 [843 kB] -Fetched 21.2 MB in 1s (36.6 MB/s) +Fetched 21.2 MB in 0s (90.8 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 ... 19703 files and directories currently installed.) @@ -333,7 +365,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/90302/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/90302/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 @@ -355,43 +391,43 @@ Writing Makefile for Mojolicious::Plugin::CGI Writing MYMETA.yml and MYMETA.json dh_auto_build - make -j11 + make -j21 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 -j11 test TEST_VERBOSE=1 + make -j21 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(0x58c0427c)) (Test::Pod::Coverage is required) +ok 3 # skip pod_coverage_ok(Mojolicious::Plugin::CGI HASH(0x58c89e74)) (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-05-19 17:16:57.16253] [46189] [trace] [kQufB5eFQCuQ] GET "/working" -[2024-05-19 17:16:57.16325] [46189] [trace] [kQufB5eFQCuQ] Routing to a callback -[2024-05-19 17:16:57.16662] [46189] [debug] [CGI:basic.pl:46198] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl -[2024-05-19 17:16:57.20577] [46189] [trace] [kQufB5eFQCuQ] 200 OK (0.043267s, 23.112/s) -[2024-05-19 17:16:57.21496] [46189] [trace] [DmoczMeDDX21] GET "/env/basic/foo" -[2024-05-19 17:16:57.21564] [46189] [trace] [DmoczMeDDX21] Routing to a callback -[2024-05-19 17:16:57.21862] [46189] [debug] [CGI:env.cgi:46199] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:16:57.24095] [46189] [trace] [DmoczMeDDX21] 200 OK (0.025947s, 38.540/s) -[2024-05-19 17:16:57.25884] [46189] [trace] [DI0X7SGC6cxS] GET "/env/basic/foo" -[2024-05-19 17:16:57.25916] [46189] [trace] [DI0X7SGC6cxS] Routing to a callback -[2024-05-19 17:16:57.26230] [46189] [debug] [CGI:env.cgi:46201] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:16:57.28460] [46189] [trace] [DI0X7SGC6cxS] 200 OK (0.025721s, 38.879/s) +[2025-06-21 23:40:40.30718] [95140] [trace] [TjBnJcOUjiNR] GET "/working" +[2025-06-21 23:40:40.30755] [95140] [trace] [TjBnJcOUjiNR] Routing to a callback +[2025-06-21 23:40:40.30896] [95140] [debug] [CGI:basic.pl:95307] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl +[2025-06-21 23:40:40.31773] [95140] [trace] [TjBnJcOUjiNR] 200 OK (0.010534s, 94.931/s) +[2025-06-21 23:40:40.32243] [95140] [trace] [0z2rZA7Fmiui] GET "/env/basic/foo" +[2025-06-21 23:40:40.32279] [95140] [trace] [0z2rZA7Fmiui] Routing to a callback +[2025-06-21 23:40:40.32387] [95140] [debug] [CGI:env.cgi:95316] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:40.33247] [95140] [trace] [0z2rZA7Fmiui] 200 OK (0.010026s, 99.741/s) +[2025-06-21 23:40:40.34347] [95140] [trace] [yuuAAqvkXcg4] GET "/env/basic/foo" +[2025-06-21 23:40:40.34367] [95140] [trace] [yuuAAqvkXcg4] Routing to a callback +[2025-06-21 23:40:40.34479] [95140] [debug] [CGI:env.cgi:95327] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:40.35305] [95140] [trace] [yuuAAqvkXcg4] 200 OK (0.009556s, 104.646/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:45527/env/basic/foo?query=123 +ok 5 - GET http://127.0.0.1:38451/env/basic/foo?query=123 ok 6 - 200 OK ok 7 - ENVIRONMENT ok 8 - CONTENT_LENGTH=0 @@ -421,10 +457,10 @@ ok 32 - HTTP_X_FORWARDED_FOR=1.2.3.4 1..32 ok -[2024-05-19 17:16:58.23950] [46202] [trace] [YviFa6WnZ9Gs] GET "/user/42" -[2024-05-19 17:16:58.24021] [46202] [trace] [YviFa6WnZ9Gs] Routing to a callback -[2024-05-19 17:16:58.24328] [46202] [debug] [CGI:env.cgi:46210] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:16:58.26533] [46202] [trace] [YviFa6WnZ9Gs] 200 OK (0.025807s, 38.749/s) +[2025-06-21 23:40:40.68249] [95338] [trace] [RKz1ki4hmCSH] GET "/user/42" +[2025-06-21 23:40:40.68289] [95338] [trace] [RKz1ki4hmCSH] Routing to a callback +[2025-06-21 23:40:40.68432] [95338] [debug] [CGI:env.cgi:95503] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:40.69338] [95338] [trace] [RKz1ki4hmCSH] 200 OK (0.010877s, 91.937/s) t/before.t ....................... ok 1 - GET /user/42 ok 2 - 200 OK @@ -432,21 +468,21 @@ ok 4 - QUERY_STRING=...other_value=123 1..4 ok -[2024-05-19 17:16:59.09978] [46212] [trace] [Y4pyDPPMpbp1] GET "/foo" -[2024-05-19 17:16:59.10058] [46212] [trace] [Y4pyDPPMpbp1] Routing to a callback -[2024-05-19 17:16:59.10426] [46212] [debug] [CGI:CODE(0x58a62638):46220] START CODE(0x58a62638) +[2025-06-21 23:40:41.02583] [95512] [trace] [ZbAuOyD9X-Wg] GET "/foo" +[2025-06-21 23:40:41.02622] [95512] [trace] [ZbAuOyD9X-Wg] Routing to a callback +[2025-06-21 23:40:41.02762] [95512] [debug] [CGI:CODE(0x598ef678):95679] START CODE(0x598ef678) # PATH_INFO=/foo -[2024-05-19 17:16:59.11797] [46212] [trace] [Y4pyDPPMpbp1] 200 OK (0.018106s, 55.230/s) -[2024-05-19 17:16:59.13017] [46212] [trace] [1fA77lT79OFM] GET "/f%C3%B6%C3%B6" -[2024-05-19 17:16:59.13071] [46212] [trace] [1fA77lT79OFM] Routing to a callback -[2024-05-19 17:16:59.13426] [46212] [debug] [CGI:CODE(0x58a62638):46225] START CODE(0x58a62638) +[2025-06-21 23:40:41.03293] [95512] [trace] [ZbAuOyD9X-Wg] 200 OK (0.007086s, 141.123/s) +[2025-06-21 23:40:41.03728] [95512] [trace] [g8NGZTDnnBPb] GET "/f%C3%B6%C3%B6" +[2025-06-21 23:40:41.03756] [95512] [trace] [g8NGZTDnnBPb] Routing to a callback +[2025-06-21 23:40:41.03864] [95512] [debug] [CGI:CODE(0x598ef678):95686] START CODE(0x598ef678) # PATH_INFO=/föö -[2024-05-19 17:16:59.14932] [46212] [trace] [1fA77lT79OFM] 200 OK (0.019092s, 52.378/s) -[2024-05-19 17:16:59.15865] [46212] [trace] [QsItDTf9ra4a] GET "/f%C3%B6%E2%80%99" -[2024-05-19 17:16:59.15922] [46212] [trace] [QsItDTf9ra4a] Routing to a callback -[2024-05-19 17:16:59.16267] [46212] [debug] [CGI:CODE(0x58a62638):46227] START CODE(0x58a62638) +[2025-06-21 23:40:41.04338] [95512] [trace] [g8NGZTDnnBPb] 200 OK (0.006082s, 164.420/s) +[2025-06-21 23:40:41.04735] [95512] [trace] [0vAf4vsr8DWW] GET "/f%C3%B6%E2%80%99" +[2025-06-21 23:40:41.04762] [95512] [trace] [0vAf4vsr8DWW] Routing to a callback +[2025-06-21 23:40:41.04870] [95512] [debug] [CGI:CODE(0x598ef678):95692] START CODE(0x598ef678) # PATH_INFO=/fö’ -[2024-05-19 17:16:59.17799] [46212] [trace] [QsItDTf9ra4a] 200 OK (0.019235s, 51.989/s) +[2025-06-21 23:40:41.05346] [95512] [trace] [0vAf4vsr8DWW] 200 OK (0.006077s, 164.555/s) t/encoding.t ..................... ok 1 - GET /foo ok 2 - 200 OK @@ -460,7 +496,7 @@ ok 10 - no warnings 1..10 ok -[2024-05-19 17:16:59.98684] [46228] [warn] [CGI:errlog:46242] yikes! at /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/errlog line 4. +[2025-06-21 23:40:41.41100] [95700] [warn] [CGI:errlog:95736] 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 @@ -471,19 +507,19 @@ 1..6 ok t/gh-16-loop_reset.t ............. skipped: set TEST_MORBO to enable this test (developer only!) -[2024-05-19 17:17:01.50951] [46257] [trace] [aXhWHZb3H9BA] GET "/cgi-bin/nope.cgi/foo" -[2024-05-19 17:17:01.51021] [46257] [trace] [aXhWHZb3H9BA] Routing to a callback +[2025-06-21 23:40:42.03770] [95836] [trace] [ld-on7-7NTLE] GET "/cgi-bin/nope.cgi/foo" +[2025-06-21 23:40:42.03819] [95836] [trace] [ld-on7-7NTLE] Routing to a callback Use of uninitialized value in print at t/Helper.pm line 18. -[2024-05-19 17:17:01.51353] [46257] [debug] [CGI:nope.cgi:46261] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi -[2024-05-19 17:17:01.54420] [46257] [trace] [aXhWHZb3H9BA] 500 Internal Server Error (0.034643s, 28.866/s) -[2024-05-19 17:17:01.55109] [46257] [trace] [8W2GJsnd8j-V] GET "/cgi-bin/env.cgi/some/path/info" -[2024-05-19 17:17:01.55168] [46257] [trace] [8W2GJsnd8j-V] Routing to a callback -[2024-05-19 17:17:01.55545] [46257] [debug] [CGI:env.cgi:46264] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:17:01.59701] [46257] [trace] [8W2GJsnd8j-V] 200 OK (0.04588s, 21.796/s) -[2024-05-19 17:17:01.61572] [46257] [trace] [ywZ3s6aSyrZD] GET "/cgi-bin/env/some/path/info" -[2024-05-19 17:17:01.61630] [46257] [trace] [ywZ3s6aSyrZD] Routing to a callback -[2024-05-19 17:17:01.62811] [46257] [debug] [CGI:env.cgi:46265] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:17:01.66110] [46257] [trace] [ywZ3s6aSyrZD] 200 OK (0.045339s, 22.056/s) +[2025-06-21 23:40:42.04003] [95836] [debug] [CGI:nope.cgi:95840] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi +[2025-06-21 23:40:42.04869] [95836] [trace] [ld-on7-7NTLE] 500 Internal Server Error (0.010979s, 91.083/s) +[2025-06-21 23:40:42.05247] [95836] [trace] [TDDi7DcwbQuM] GET "/cgi-bin/env.cgi/some/path/info" +[2025-06-21 23:40:42.05280] [95836] [trace] [TDDi7DcwbQuM] Routing to a callback +[2025-06-21 23:40:42.12683] [95836] [debug] [CGI:env.cgi:95841] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:42.13538] [95836] [trace] [TDDi7DcwbQuM] 200 OK (0.082886s, 12.065/s) +[2025-06-21 23:40:42.14070] [95836] [trace] [C5qGFcAf1u77] GET "/cgi-bin/env/some/path/info" +[2025-06-21 23:40:42.14102] [95836] [trace] [C5qGFcAf1u77] Routing to a callback +[2025-06-21 23:40:42.14232] [95836] [debug] [CGI:env.cgi:95842] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:42.15061] [95836] [trace] [C5qGFcAf1u77] 200 OK (0.009893s, 101.082/s) t/helper.t ....................... ok 1 - GET /cgi-bin/nope.cgi/foo ok 2 - 500 Internal Server Error @@ -502,50 +538,50 @@ ok 15 - SCRIPT_NAME 1..15 ok -[2024-05-19 17:17:02.59347] [46266] [trace] [Fo-pKJCw1Hlg] POST "/file_upload" -[2024-05-19 17:17:02.59437] [46266] [trace] [Fo-pKJCw1Hlg] Routing to a callback -[2024-05-19 17:17:02.59875] [46266] [debug] [CGI:file_upload:46272] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload -[2024-05-19 17:17:02.64530] [46266] [trace] [Fo-pKJCw1Hlg] 200 OK (0.051798s, 19.306/s) +[2025-06-21 23:40:42.47999] [95843] [trace] [Jeh03RV0C4nl] POST "/file_upload" +[2025-06-21 23:40:42.48040] [95843] [trace] [Jeh03RV0C4nl] Routing to a callback +[2025-06-21 23:40:42.48203] [95843] [debug] [CGI:file_upload:95844] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload +[2025-06-21 23:40:42.49126] [95843] [trace] [Jeh03RV0C4nl] 200 OK (0.01126s, 88.810/s) t/multipart.t .................... ok 1 - POST /file_upload ok 2 - 200 OK ok 3 - content is similar 1..3 ok -[2024-05-19 17:17:03.53520] [46273] [trace] [rYTxI2wnFZCE] GET "/not-found" -[2024-05-19 17:17:03.53584] [46273] [trace] [rYTxI2wnFZCE] Routing to a callback -[2024-05-19 17:17:03.53912] [46273] [debug] [CGI:not-found.pl:46295] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl -[2024-05-19 17:17:03.55974] [46273] [trace] [rYTxI2wnFZCE] 404 Not Found (0.024511s, 40.798/s) +[2025-06-21 23:40:42.82600] [95845] [trace] [vhLlvlrbtZGP] GET "/not-found" +[2025-06-21 23:40:42.82645] [95845] [trace] [vhLlvlrbtZGP] Routing to a callback +[2025-06-21 23:40:42.82802] [95845] [debug] [CGI:not-found.pl:95850] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl +[2025-06-21 23:40:42.83764] [95845] [trace] [vhLlvlrbtZGP] 404 Not Found (0.011623s, 86.036/s) t/not-found.t .................... ok 1 - GET /not-found ok 2 - 404 Not Found ok 3 - content is similar 1..3 ok -[2024-05-19 17:17:04.50475] [46296] [trace] [MntI1mpYNivK] GET "/not-modified" -[2024-05-19 17:17:04.50548] [46296] [trace] [MntI1mpYNivK] Routing to a callback -[2024-05-19 17:17:04.50881] [46296] [debug] [CGI:not-modified.pl:46302] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl -[2024-05-19 17:17:04.53099] [46296] [trace] [MntI1mpYNivK] 304 Not Modified (0.02621s, 38.153/s) +[2025-06-21 23:40:43.16904] [95851] [trace] [mA2LKAC7Xi4H] GET "/not-modified" +[2025-06-21 23:40:43.16934] [95851] [trace] [mA2LKAC7Xi4H] Routing to a callback +[2025-06-21 23:40:43.17072] [95851] [debug] [CGI:not-modified.pl:95858] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl +[2025-06-21 23:40:43.17971] [95851] [trace] [mA2LKAC7Xi4H] 304 Not Modified (0.010664s, 93.773/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-05-19 17:17:05.58462] [46304] [trace] [mjkS3Xdxo4NE] GET "/nph-borked" -[2024-05-19 17:17:05.58529] [46304] [trace] [mjkS3Xdxo4NE] Routing to a callback -[2024-05-19 17:17:05.58862] [46304] [debug] [CGI:nph-borked.pl:46317] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl -[2024-05-19 17:17:05.61602] [46304] [trace] [mjkS3Xdxo4NE] 403 Forbidden (0.031353s, 31.895/s) +[2025-06-21 23:40:43.53597] [95859] [trace] [bpJGmPDM6NRz] GET "/nph-borked" +[2025-06-21 23:40:43.53632] [95859] [trace] [bpJGmPDM6NRz] Routing to a callback +[2025-06-21 23:40:43.53780] [95859] [debug] [CGI:nph-borked.pl:95869] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl +[2025-06-21 23:40:43.54840] [95859] [trace] [bpJGmPDM6NRz] 403 Forbidden (0.01241s, 80.580/s) t/nph-borked.t ................... ok 1 - GET /nph-borked ok 2 - 403 Forbidden ok 3 - content is similar 1..3 ok -[2024-05-19 17:17:06.52559] [46319] [trace] [-txhJ5TlzViN] GET "/nph" -[2024-05-19 17:17:06.52626] [46319] [trace] [-txhJ5TlzViN] Routing to a callback -[2024-05-19 17:17:06.52942] [46319] [debug] [CGI:nph.pl:46323] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl -[2024-05-19 17:17:06.55043] [46319] [trace] [-txhJ5TlzViN] 403 Payment Required (0.024801s, 40.321/s) +[2025-06-21 23:40:43.90222] [95873] [trace] [ETMUVi31JEwE] GET "/nph" +[2025-06-21 23:40:43.90256] [95873] [trace] [ETMUVi31JEwE] Routing to a callback +[2025-06-21 23:40:43.90408] [95873] [debug] [CGI:nph.pl:95893] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl +[2025-06-21 23:40:43.91362] [95873] [trace] [ETMUVi31JEwE] 403 Payment Required (0.011407s, 87.665/s) t/nph.t .......................... ok 1 - GET /nph ok 2 - 403 Forbidden @@ -553,10 +589,10 @@ 1..3 ok t/post.t ......................... skipped: TEST_PIPES=1; No idea how to test this consistently -[2024-05-19 17:17:08.26656] [46325] [trace] [dOEt4fJPH02l] GET "/env/basic/foo" -[2024-05-19 17:17:08.26728] [46325] [trace] [dOEt4fJPH02l] Routing to a callback -[2024-05-19 17:17:08.27051] [46325] [debug] [CGI:env.cgi:46330] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:17:08.32904] [46325] [trace] [dOEt4fJPH02l] 200 OK (0.06245s, 16.013/s) +[2025-06-21 23:40:44.61902] [95899] [trace] [BJuAnjgWJglO] GET "/env/basic/foo" +[2025-06-21 23:40:44.61942] [95899] [trace] [BJuAnjgWJglO] Routing to a callback +[2025-06-21 23:40:44.62089] [95899] [debug] [CGI:env.cgi:95911] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:44.62934] [95899] [trace] [BJuAnjgWJglO] 200 OK (0.010309s, 97.003/s) t/query-string-with-semicolon.t .. ok 1 - GET /env/basic/foo?a=1;b=2 ok 2 - 200 OK @@ -568,10 +604,10 @@ # expected: '1' 1..4 ok -[2024-05-19 17:17:09.03862] [46333] [trace] [Suc-LWCTOSM1] GET "/redirect" -[2024-05-19 17:17:09.03926] [46333] [trace] [Suc-LWCTOSM1] Routing to a callback -[2024-05-19 17:17:09.04251] [46333] [debug] [CGI:redirect.pl:46340] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl -[2024-05-19 17:17:09.08167] [46333] [trace] [Suc-LWCTOSM1] 302 Found (0.043006s, 23.253/s) +[2025-06-21 23:40:44.96810] [95912] [trace] [BMWn4D6oL4_l] GET "/redirect" +[2025-06-21 23:40:44.96850] [95912] [trace] [BMWn4D6oL4_l] Routing to a callback +[2025-06-21 23:40:44.96994] [95912] [debug] [CGI:redirect.pl:95915] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl +[2025-06-21 23:40:44.97886] [95912] [trace] [BMWn4D6oL4_l] 302 Found (0.010747s, 93.049/s) t/redirect.t ..................... ok 1 - GET /redirect ok 2 - 302 Found @@ -579,35 +615,35 @@ ok 4 - exact match for content 1..4 ok -[2024-05-19 17:17:10.21722] [46341] [trace] [infEx5EchGXi] GET "/auth" -[2024-05-19 17:17:10.21781] [46341] [trace] [infEx5EchGXi] Routing to a callback -[2024-05-19 17:17:10.22091] [46341] [debug] [CGI:env.cgi:46366] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:17:10.24102] [46341] [trace] [infEx5EchGXi] 200 OK (0.023775s, 42.061/s) -[2024-05-19 17:17:10.25176] [46341] [trace] [T2tJ2R7K-fVY] GET "/auth" -[2024-05-19 17:17:10.25229] [46341] [trace] [T2tJ2R7K-fVY] Routing to a callback -[2024-05-19 17:17:10.25470] [46341] [debug] [CGI:env.cgi:46369] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:17:10.28896] [46341] [trace] [T2tJ2R7K-fVY] 200 OK (0.037169s, 26.904/s) -[2024-05-19 17:17:10.30155] [46341] [trace] [PXUQex_oke0c] GET "/auth" -[2024-05-19 17:17:10.30190] [46341] [trace] [PXUQex_oke0c] Routing to a callback -[2024-05-19 17:17:10.30647] [46341] [debug] [CGI:env.cgi:46372] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-19 17:17:10.33222] [46341] [trace] [PXUQex_oke0c] 200 OK (0.03062s, 32.658/s) +[2025-06-21 23:40:45.31919] [95916] [trace] [kuSAALX6FC9E] GET "/auth" +[2025-06-21 23:40:45.31959] [95916] [trace] [kuSAALX6FC9E] Routing to a callback +[2025-06-21 23:40:45.32111] [95916] [debug] [CGI:env.cgi:95927] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:45.32966] [95916] [trace] [kuSAALX6FC9E] 200 OK (0.010465s, 95.557/s) +[2025-06-21 23:40:45.33417] [95916] [trace] [MQU8qD9qt9Sg] GET "/auth" +[2025-06-21 23:40:45.33436] [95916] [trace] [MQU8qD9qt9Sg] Routing to a callback +[2025-06-21 23:40:45.33544] [95916] [debug] [CGI:env.cgi:95928] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:45.34359] [95916] [trace] [MQU8qD9qt9Sg] 200 OK (0.0094s, 106.383/s) +[2025-06-21 23:40:45.34788] [95916] [trace] [wB6JXPD_kRT5] GET "/auth" +[2025-06-21 23:40:45.34810] [95916] [trace] [wB6JXPD_kRT5] Routing to a callback +[2025-06-21 23:40:45.34920] [95916] [debug] [CGI:env.cgi:95929] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-21 23:40:45.35710] [95916] [trace] [wB6JXPD_kRT5] 200 OK (0.009199s, 108.707/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:35453/auth +ok 5 - GET http://127.0.0.1:36417/auth ok 6 - 200 OK ok 7 - REMOTE_USER=Aladdin -ok 8 - GET http://127.0.0.1:35453/auth +ok 8 - GET http://127.0.0.1:36417/auth ok 9 - 200 OK ok 10 - REMOTE_USER=Aladdin 1..10 ok -[2024-05-19 17:17:11.26336] [46377] [trace] [MgrS19Ams8nO] GET "/" -[2024-05-19 17:17:11.26451] [46377] [trace] [MgrS19Ams8nO] Routing to a callback -[2024-05-19 17:17:11.26713] [46377] [debug] [CGI:CODE(0x581f8250):46428] START CODE(0x581f8250) -[2024-05-19 17:17:11.27721] [46377] [trace] [MgrS19Ams8nO] 200 OK (0.013816s, 72.380/s) +[2025-06-21 23:40:45.68465] [95932] [trace] [zO4vMMFrzpb1] GET "/" +[2025-06-21 23:40:45.68509] [95932] [trace] [zO4vMMFrzpb1] Routing to a callback +[2025-06-21 23:40:45.68655] [95932] [debug] [CGI:CODE(0x587b3310):95946] START CODE(0x587b3310) +[2025-06-21 23:40:45.69082] [95932] [trace] [zO4vMMFrzpb1] 200 OK (0.006148s, 162.655/s) t/run.t .......................... ok 1 - GET / ok 2 - 200 OK @@ -616,13 +652,13 @@ ok t/zombies.t ...................... skipped: (no reason given) All tests successful. -Files=18, Tests=110, 16 wallclock secs ( 0.12 usr 0.02 sys + 12.10 cusr 1.36 csys = 13.60 CPU) +Files=18, Tests=110, 6 wallclock secs ( 0.06 usr 0.02 sys + 5.42 cusr 0.64 csys = 6.14 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 -j11 install DESTDIR=/build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/debian/libmojolicious-plugin-cgi-perl AM_UPDATE_INFO_DIR=no PREFIX=/usr + make -j21 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 @@ -649,12 +685,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/90302/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/90302/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/44159 and its subdirectories -I: Current time: Sun May 19 05:17:17 -12 2024 -I: pbuilder-time-stamp: 1716139037 +I: removing directory /srv/workspace/pbuilder/90302 and its subdirectories +I: Current time: Sun Jun 22 13:40:48 +14 2025 +I: pbuilder-time-stamp: 1750549248