Diff of the two buildlogs: -- --- b1/build.log 2024-05-07 02:15:50.041802540 +0000 +++ b2/build.log 2024-05-07 02:16:30.768069664 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon May 6 14:15:12 -12 2024 -I: pbuilder-time-stamp: 1715048112 +I: Current time: Mon Jun 9 22:38:52 +14 2025 +I: pbuilder-time-stamp: 1749458332 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-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/3169494/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/371255/tmp/hooks/D01_modify_environment starting +debug: Running on codethink03-arm64. +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 9 08:38 /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/371255/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/371255/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='arm64' - DEBIAN_FRONTEND='noninteractive' + 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]="aarch64-unknown-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=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=12 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='d7786b61480645f7a9db6a3221cf3f09' - 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='3169494' - PS1='# ' - PS2='> ' + INVOCATION_ID=18a662d8b47744ca8e599cf8726cb585 + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-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=371255 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.b7Z4cm9k/pbuilderrc_xEwX --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.b7Z4cm9k/b1 --logfile b1/build.log libmojolicious-plugin-cgi-perl_0.40-2.dsc' - SUDO_GID='109' - SUDO_UID='104' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.4: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.b7Z4cm9k/pbuilderrc_2qZy --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.b7Z4cm9k/b2 --logfile b2/build.log libmojolicious-plugin-cgi-perl_0.40-2.dsc' + SUDO_GID=109 + SUDO_UID=104 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://192.168.101.4:3128 I: uname -a - Linux codethink04-arm64 6.1.0-20-cloud-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-cloud-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 5 07:43 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/3169494/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 7 14:05 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/371255/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -146,7 +178,7 @@ Get: 34 http://deb.debian.org/debian unstable/main arm64 libjs-bootstrap4 all 4.6.1+dfsg1-4 [517 kB] Get: 35 http://deb.debian.org/debian unstable/main arm64 libjs-highlight.js all 9.18.5+dfsg1-2 [397 kB] Get: 36 http://deb.debian.org/debian unstable/main arm64 libmojolicious-perl all 9.36+dfsg-1 [842 kB] -Fetched 20.5 MB in 0s (74.4 MB/s) +Fetched 20.5 MB in 0s (152 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 ... 19744 files and directories currently installed.) @@ -305,7 +337,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/371255/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/371255/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 @@ -340,30 +376,30 @@ 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(0xaaaad4c74120)) (Test::Pod::Coverage is required) +ok 3 # skip pod_coverage_ok(Mojolicious::Plugin::CGI HASH(0xaaaad38c0ee0)) (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-07 02:15:38.58235] [3172587] [trace] [8Yay4VZch_2p] GET "/working" -[2024-05-07 02:15:38.58284] [3172587] [trace] [8Yay4VZch_2p] Routing to a callback -[2024-05-07 02:15:38.58484] [3172587] [debug] [CGI:basic.pl:3172605] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl -[2024-05-07 02:15:38.59699] [3172587] [trace] [8Yay4VZch_2p] 200 OK (0.014614s, 68.428/s) -[2024-05-07 02:15:38.60475] [3172587] [trace] [HYAFX2QcgMlC] GET "/env/basic/foo" -[2024-05-07 02:15:38.60525] [3172587] [trace] [HYAFX2QcgMlC] Routing to a callback -[2024-05-07 02:15:38.60714] [3172587] [debug] [CGI:env.cgi:3172609] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:38.62122] [3172587] [trace] [HYAFX2QcgMlC] 200 OK (0.016432s, 60.857/s) -[2024-05-07 02:15:38.63283] [3172587] [trace] [0zgegJaxlLRc] GET "/env/basic/foo" -[2024-05-07 02:15:38.63306] [3172587] [trace] [0zgegJaxlLRc] Routing to a callback -[2024-05-07 02:15:38.63494] [3172587] [debug] [CGI:env.cgi:3172616] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:38.66809] [3172587] [trace] [0zgegJaxlLRc] 200 OK (0.035234s, 28.382/s) +[2025-06-09 08:39:17.58511] [378934] [trace] [vC_3rhpD7PHp] GET "/working" +[2025-06-09 08:39:17.58562] [378934] [trace] [vC_3rhpD7PHp] Routing to a callback +[2025-06-09 08:39:17.59125] [378934] [debug] [CGI:basic.pl:378990] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/basic.pl +[2025-06-09 08:39:17.61613] [378934] [trace] [vC_3rhpD7PHp] 200 OK (0.030986s, 32.273/s) +[2025-06-09 08:39:17.63172] [378934] [trace] [rZaPKRYIhg6u] GET "/env/basic/foo" +[2025-06-09 08:39:17.63225] [378934] [trace] [rZaPKRYIhg6u] Routing to a callback +[2025-06-09 08:39:17.63373] [378934] [debug] [CGI:env.cgi:378994] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:17.66401] [378934] [trace] [rZaPKRYIhg6u] 200 OK (0.032257s, 31.001/s) +[2025-06-09 08:39:17.67833] [378934] [trace] [JCSVgP2gcrlT] GET "/env/basic/foo" +[2025-06-09 08:39:17.67856] [378934] [trace] [JCSVgP2gcrlT] Routing to a callback +[2025-06-09 08:39:17.68805] [378934] [debug] [CGI:env.cgi:379000] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:17.73210] [378934] [trace] [JCSVgP2gcrlT] 200 OK (0.053728s, 18.612/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:33395/env/basic/foo?query=123 +ok 5 - GET http://127.0.0.1:38623/env/basic/foo?query=123 ok 6 - 200 OK ok 7 - ENVIRONMENT ok 8 - CONTENT_LENGTH=0 @@ -393,10 +429,10 @@ ok 32 - HTTP_X_FORWARDED_FOR=1.2.3.4 1..32 ok -[2024-05-07 02:15:39.14523] [3172621] [trace] [YLp-z3p0K1fe] GET "/user/42" -[2024-05-07 02:15:39.14577] [3172621] [trace] [YLp-z3p0K1fe] Routing to a callback -[2024-05-07 02:15:39.14797] [3172621] [debug] [CGI:env.cgi:3172650] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:39.18011] [3172621] [trace] [YLp-z3p0K1fe] 200 OK (0.034854s, 28.691/s) +[2025-06-09 08:39:18.35456] [379007] [trace] [GDYc1wjUBv2y] GET "/user/42" +[2025-06-09 08:39:18.35507] [379007] [trace] [GDYc1wjUBv2y] Routing to a callback +[2025-06-09 08:39:18.36104] [379007] [debug] [CGI:env.cgi:379091] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:18.39215] [379007] [trace] [GDYc1wjUBv2y] 200 OK (0.037567s, 26.619/s) t/before.t ....................... ok 1 - GET /user/42 ok 2 - 200 OK @@ -404,21 +440,21 @@ ok 4 - QUERY_STRING=...other_value=123 1..4 ok -[2024-05-07 02:15:39.54412] [3172654] [trace] [lWxhwu3GsRR8] GET "/foo" -[2024-05-07 02:15:39.54460] [3172654] [trace] [lWxhwu3GsRR8] Routing to a callback -[2024-05-07 02:15:39.54674] [3172654] [debug] [CGI:CODE(0xaaab1b0e6688):3172693] START CODE(0xaaab1b0e6688) +[2025-06-09 08:39:18.75358] [379103] [trace] [ASbTsDb8RwOJ] GET "/foo" +[2025-06-09 08:39:18.75408] [379103] [trace] [ASbTsDb8RwOJ] Routing to a callback +[2025-06-09 08:39:18.75630] [379103] [debug] [CGI:CODE(0xaaaae8d2df20):379167] START CODE(0xaaaae8d2df20) # PATH_INFO=/foo -[2024-05-07 02:15:39.55625] [3172654] [trace] [lWxhwu3GsRR8] 200 OK (0.012106s, 82.604/s) -[2024-05-07 02:15:39.56671] [3172654] [trace] [GuiGW4s7b-pD] GET "/f%C3%B6%C3%B6" -[2024-05-07 02:15:39.56711] [3172654] [trace] [GuiGW4s7b-pD] Routing to a callback -[2024-05-07 02:15:39.57023] [3172654] [debug] [CGI:CODE(0xaaab1b0e6688):3172699] START CODE(0xaaab1b0e6688) +[2025-06-09 08:39:18.76434] [379103] [trace] [ASbTsDb8RwOJ] 200 OK (0.010735s, 93.153/s) +[2025-06-09 08:39:18.77332] [379103] [trace] [kTviVVf2LmJw] GET "/f%C3%B6%C3%B6" +[2025-06-09 08:39:18.77370] [379103] [trace] [kTviVVf2LmJw] Routing to a callback +[2025-06-09 08:39:18.77572] [379103] [debug] [CGI:CODE(0xaaaae8d2df20):379170] START CODE(0xaaaae8d2df20) # PATH_INFO=/föö -[2024-05-07 02:15:39.58418] [3172654] [trace] [GuiGW4s7b-pD] 200 OK (0.017428s, 57.379/s) -[2024-05-07 02:15:39.59098] [3172654] [trace] [QmTMoiG1u-7D] GET "/f%C3%B6%E2%80%99" -[2024-05-07 02:15:39.59552] [3172654] [trace] [QmTMoiG1u-7D] Routing to a callback -[2024-05-07 02:15:39.59756] [3172654] [debug] [CGI:CODE(0xaaab1b0e6688):3172708] START CODE(0xaaab1b0e6688) +[2025-06-09 08:39:18.78392] [379103] [trace] [kTviVVf2LmJw] 200 OK (0.010577s, 94.545/s) +[2025-06-09 08:39:18.79077] [379103] [trace] [DLRNcNQxpV2v] GET "/f%C3%B6%E2%80%99" +[2025-06-09 08:39:18.79149] [379103] [trace] [DLRNcNQxpV2v] Routing to a callback +[2025-06-09 08:39:18.79339] [379103] [debug] [CGI:CODE(0xaaaae8d2df20):379173] START CODE(0xaaaae8d2df20) # PATH_INFO=/fö’ -[2024-05-07 02:15:39.61610] [3172654] [trace] [QmTMoiG1u-7D] 200 OK (0.02509s, 39.857/s) +[2025-06-09 08:39:18.80306] [379103] [trace] [DLRNcNQxpV2v] 200 OK (0.012262s, 81.553/s) t/encoding.t ..................... ok 1 - GET /foo ok 2 - 200 OK @@ -432,7 +468,7 @@ ok 10 - no warnings 1..10 ok -[2024-05-07 02:15:40.19657] [3172712] [warn] [CGI:errlog:3172747] yikes! at /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/errlog line 4. +[2025-06-09 08:39:19.12335] [379177] [warn] [CGI:errlog:379227] 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 @@ -443,19 +479,19 @@ 1..6 ok t/gh-16-loop_reset.t ............. skipped: set TEST_MORBO to enable this test (developer only!) -[2024-05-07 02:15:40.84628] [3172783] [trace] [OaEuSP68MQ03] GET "/cgi-bin/nope.cgi/foo" -[2024-05-07 02:15:40.84678] [3172783] [trace] [OaEuSP68MQ03] Routing to a callback +[2025-06-09 08:39:20.31464] [379349] [trace] [pj5JQMm1_nL2] GET "/cgi-bin/nope.cgi/foo" +[2025-06-09 08:39:20.31926] [379349] [trace] [pj5JQMm1_nL2] Routing to a callback Use of uninitialized value in print at t/Helper.pm line 18. -[2024-05-07 02:15:40.84918] [3172783] [debug] [CGI:nope.cgi:3172814] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi -[2024-05-07 02:15:40.88822] [3172783] [trace] [OaEuSP68MQ03] 500 Internal Server Error (0.041905s, 23.864/s) -[2024-05-07 02:15:40.89450] [3172783] [trace] [Y0LV6wVyIPM7] GET "/cgi-bin/env.cgi/some/path/info" -[2024-05-07 02:15:40.89491] [3172783] [trace] [Y0LV6wVyIPM7] Routing to a callback -[2024-05-07 02:15:40.90116] [3172783] [debug] [CGI:env.cgi:3172818] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:40.92507] [3172783] [trace] [Y0LV6wVyIPM7] 200 OK (0.030533s, 32.751/s) -[2024-05-07 02:15:40.93663] [3172783] [trace] [GGpwtZMUNW_y] GET "/cgi-bin/env/some/path/info" -[2024-05-07 02:15:40.93706] [3172783] [trace] [GGpwtZMUNW_y] Routing to a callback -[2024-05-07 02:15:40.93912] [3172783] [debug] [CGI:env.cgi:3172822] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:40.96464] [3172783] [trace] [GGpwtZMUNW_y] 200 OK (0.027974s, 35.747/s) +[2025-06-09 08:39:20.32147] [379349] [debug] [CGI:nope.cgi:379483] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nope.cgi +[2025-06-09 08:39:20.34803] [379349] [trace] [pj5JQMm1_nL2] 500 Internal Server Error (0.033356s, 29.980/s) +[2025-06-09 08:39:20.35399] [379349] [trace] [PQcLAgRBPoj3] GET "/cgi-bin/env.cgi/some/path/info" +[2025-06-09 08:39:20.35439] [379349] [trace] [PQcLAgRBPoj3] Routing to a callback +[2025-06-09 08:39:20.36441] [379349] [debug] [CGI:env.cgi:379494] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:20.39267] [379349] [trace] [PQcLAgRBPoj3] 200 OK (0.038656s, 25.869/s) +[2025-06-09 08:39:20.40822] [379349] [trace] [uHdVkDoGSTb-] GET "/cgi-bin/env/some/path/info" +[2025-06-09 08:39:20.40866] [379349] [trace] [uHdVkDoGSTb-] Routing to a callback +[2025-06-09 08:39:20.41061] [379349] [debug] [CGI:env.cgi:379504] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:20.43309] [379349] [trace] [uHdVkDoGSTb-] 200 OK (0.02484s, 40.258/s) t/helper.t ....................... ok 1 - GET /cgi-bin/nope.cgi/foo ok 2 - 500 Internal Server Error @@ -474,50 +510,50 @@ ok 15 - SCRIPT_NAME 1..15 ok -[2024-05-07 02:15:41.27409] [3172826] [trace] [Hxgh5IFBCcyh] POST "/file_upload" -[2024-05-07 02:15:41.27458] [3172826] [trace] [Hxgh5IFBCcyh] Routing to a callback -[2024-05-07 02:15:41.27683] [3172826] [debug] [CGI:file_upload:3172847] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload -[2024-05-07 02:15:41.30815] [3172826] [trace] [Hxgh5IFBCcyh] 200 OK (0.033998s, 29.413/s) +[2025-06-09 08:39:21.07158] [379513] [trace] [QnekpzE2xGoO] POST "/file_upload" +[2025-06-09 08:39:21.07212] [379513] [trace] [QnekpzE2xGoO] Routing to a callback +[2025-06-09 08:39:21.07416] [379513] [debug] [CGI:file_upload:379627] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/file_upload +[2025-06-09 08:39:21.10094] [379513] [trace] [QnekpzE2xGoO] 200 OK (0.029343s, 34.080/s) t/multipart.t .................... ok 1 - POST /file_upload ok 2 - 200 OK ok 3 - content is similar 1..3 ok -[2024-05-07 02:15:41.85408] [3172851] [trace] [MlyKcffLz3a1] GET "/not-found" -[2024-05-07 02:15:41.85459] [3172851] [trace] [MlyKcffLz3a1] Routing to a callback -[2024-05-07 02:15:41.85643] [3172851] [debug] [CGI:not-found.pl:3172874] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl -[2024-05-07 02:15:41.88429] [3172851] [trace] [MlyKcffLz3a1] 404 Not Found (0.030183s, 33.131/s) +[2025-06-09 08:39:21.71610] [379640] [trace] [mlnWL2CPdKBn] GET "/not-found" +[2025-06-09 08:39:21.71658] [379640] [trace] [mlnWL2CPdKBn] Routing to a callback +[2025-06-09 08:39:21.71831] [379640] [debug] [CGI:not-found.pl:379786] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-found.pl +[2025-06-09 08:39:21.74916] [379640] [trace] [mlnWL2CPdKBn] 404 Not Found (0.033025s, 30.280/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-07 02:15:42.43176] [3172875] [trace] [_OL_fQVLWykM] GET "/not-modified" -[2024-05-07 02:15:42.43230] [3172875] [trace] [_OL_fQVLWykM] Routing to a callback -[2024-05-07 02:15:42.43402] [3172875] [debug] [CGI:not-modified.pl:3172886] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl -[2024-05-07 02:15:42.46042] [3172875] [trace] [_OL_fQVLWykM] 304 Not Modified (0.028635s, 34.922/s) +[2025-06-09 08:39:22.15887] [379796] [trace] [Jeghv9x3Gqlr] GET "/not-modified" +[2025-06-09 08:39:22.16776] [379796] [trace] [Jeghv9x3Gqlr] Routing to a callback +[2025-06-09 08:39:22.16980] [379796] [debug] [CGI:not-modified.pl:379935] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/not-modified.pl +[2025-06-09 08:39:22.19230] [379796] [trace] [Jeghv9x3Gqlr] 304 Not Modified (0.033411s, 29.930/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-07 02:15:42.75403] [3172887] [trace] [Kqtk7Cq2nxaj] GET "/nph-borked" -[2024-05-07 02:15:42.75451] [3172887] [trace] [Kqtk7Cq2nxaj] Routing to a callback -[2024-05-07 02:15:42.75644] [3172887] [debug] [CGI:nph-borked.pl:3172892] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl -[2024-05-07 02:15:42.78814] [3172887] [trace] [Kqtk7Cq2nxaj] 403 Forbidden (0.034073s, 29.349/s) +[2025-06-09 08:39:22.55294] [379946] [trace] [FZGk8wvIpYMm] GET "/nph-borked" +[2025-06-09 08:39:22.55345] [379946] [trace] [FZGk8wvIpYMm] Routing to a callback +[2025-06-09 08:39:22.55553] [379946] [debug] [CGI:nph-borked.pl:380028] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph-borked.pl +[2025-06-09 08:39:22.58129] [379946] [trace] [FZGk8wvIpYMm] 403 Forbidden (0.028326s, 35.303/s) t/nph-borked.t ................... ok 1 - GET /nph-borked ok 2 - 403 Forbidden ok 3 - content is similar 1..3 ok -[2024-05-07 02:15:43.24756] [3172894] [trace] [CRxEK_KOEebM] GET "/nph" -[2024-05-07 02:15:43.24804] [3172894] [trace] [CRxEK_KOEebM] Routing to a callback -[2024-05-07 02:15:43.25014] [3172894] [debug] [CGI:nph.pl:3172914] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl -[2024-05-07 02:15:43.26216] [3172894] [trace] [CRxEK_KOEebM] 403 Payment Required (0.014576s, 68.606/s) +[2025-06-09 08:39:23.21392] [380046] [trace] [mmegXyJakVqn] GET "/nph" +[2025-06-09 08:39:23.21443] [380046] [trace] [mmegXyJakVqn] Routing to a callback +[2025-06-09 08:39:23.22041] [380046] [debug] [CGI:nph.pl:380210] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/nph.pl +[2025-06-09 08:39:23.24963] [380046] [trace] [mmegXyJakVqn] 403 Payment Required (0.035681s, 28.026/s) t/nph.t .......................... ok 1 - GET /nph ok 2 - 403 Forbidden @@ -525,10 +561,10 @@ 1..3 ok t/post.t ......................... skipped: TEST_PIPES=1; No idea how to test this consistently -[2024-05-07 02:15:43.83293] [3172930] [trace] [rPjSbT1cR-nF] GET "/env/basic/foo" -[2024-05-07 02:15:43.83344] [3172930] [trace] [rPjSbT1cR-nF] Routing to a callback -[2024-05-07 02:15:43.83577] [3172930] [debug] [CGI:env.cgi:3172952] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:43.86815] [3172930] [trace] [rPjSbT1cR-nF] 200 OK (0.035202s, 28.407/s) +[2025-06-09 08:39:24.17150] [380376] [trace] [cbiEVu_W6qib] GET "/env/basic/foo" +[2025-06-09 08:39:24.17199] [380376] [trace] [cbiEVu_W6qib] Routing to a callback +[2025-06-09 08:39:24.17374] [380376] [debug] [CGI:env.cgi:380417] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:24.20420] [380376] [trace] [cbiEVu_W6qib] 200 OK (0.03268s, 30.600/s) t/query-string-with-semicolon.t .. ok 1 - GET /env/basic/foo?a=1;b=2 ok 2 - 200 OK @@ -540,10 +576,10 @@ # expected: '1' 1..4 ok -[2024-05-07 02:15:44.37035] [3172955] [trace] [21b54CNvj0do] GET "/redirect" -[2024-05-07 02:15:44.37085] [3172955] [trace] [21b54CNvj0do] Routing to a callback -[2024-05-07 02:15:44.37302] [3172955] [debug] [CGI:redirect.pl:3172989] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl -[2024-05-07 02:15:44.38502] [3172955] [trace] [21b54CNvj0do] 302 Found (0.014641s, 68.301/s) +[2025-06-09 08:39:24.80926] [380438] [trace] [Y12z8e1ATeKw] GET "/redirect" +[2025-06-09 08:39:24.80973] [380438] [trace] [Y12z8e1ATeKw] Routing to a callback +[2025-06-09 08:39:24.81354] [380438] [debug] [CGI:redirect.pl:380583] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/redirect.pl +[2025-06-09 08:39:24.83775] [380438] [trace] [Y12z8e1ATeKw] 302 Found (0.028429s, 35.175/s) t/redirect.t ..................... ok 1 - GET /redirect ok 2 - 302 Found @@ -551,35 +587,35 @@ ok 4 - exact match for content 1..4 ok -[2024-05-07 02:15:44.68714] [3172990] [trace] [bt1utqzeigH-] GET "/auth" -[2024-05-07 02:15:44.68765] [3172990] [trace] [bt1utqzeigH-] Routing to a callback -[2024-05-07 02:15:44.68938] [3172990] [debug] [CGI:env.cgi:3173004] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:44.71630] [3172990] [trace] [bt1utqzeigH-] 200 OK (0.029129s, 34.330/s) -[2024-05-07 02:15:44.72812] [3172990] [trace] [c3jYhTpGtu6T] GET "/auth" -[2024-05-07 02:15:44.72837] [3172990] [trace] [c3jYhTpGtu6T] Routing to a callback -[2024-05-07 02:15:44.73016] [3172990] [debug] [CGI:env.cgi:3173010] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:44.75616] [3172990] [trace] [c3jYhTpGtu6T] 200 OK (0.028014s, 35.696/s) -[2024-05-07 02:15:44.76764] [3172990] [trace] [xBznVoODdzLu] GET "/auth" -[2024-05-07 02:15:44.76792] [3172990] [trace] [xBznVoODdzLu] Routing to a callback -[2024-05-07 02:15:44.76976] [3172990] [debug] [CGI:env.cgi:3173012] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi -[2024-05-07 02:15:44.79596] [3172990] [trace] [xBznVoODdzLu] 200 OK (0.028299s, 35.337/s) +[2025-06-09 08:39:25.15800] [380594] [trace] [y_XQFB3Gc02F] GET "/auth" +[2025-06-09 08:39:25.15853] [380594] [trace] [y_XQFB3Gc02F] Routing to a callback +[2025-06-09 08:39:25.16077] [380594] [debug] [CGI:env.cgi:380687] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:25.19602] [380594] [trace] [y_XQFB3Gc02F] 200 OK (0.037989s, 26.323/s) +[2025-06-09 08:39:25.20746] [380594] [trace] [UsI7nmrxO-lP] GET "/auth" +[2025-06-09 08:39:25.20774] [380594] [trace] [UsI7nmrxO-lP] Routing to a callback +[2025-06-09 08:39:25.20970] [380594] [debug] [CGI:env.cgi:380701] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:25.24797] [380594] [trace] [UsI7nmrxO-lP] 200 OK (0.040794s, 24.513/s) +[2025-06-09 08:39:25.25906] [380594] [trace] [5vLG1iXlN1t6] GET "/auth" +[2025-06-09 08:39:25.26366] [380594] [trace] [5vLG1iXlN1t6] Routing to a callback +[2025-06-09 08:39:25.26580] [380594] [debug] [CGI:env.cgi:380717] START /build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40/t/cgi-bin/env.cgi +[2025-06-09 08:39:25.29363] [380594] [trace] [5vLG1iXlN1t6] 200 OK (0.034529s, 28.961/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:42185/auth +ok 5 - GET http://127.0.0.1:41137/auth ok 6 - 200 OK ok 7 - REMOTE_USER=Aladdin -ok 8 - GET http://127.0.0.1:42185/auth +ok 8 - GET http://127.0.0.1:41137/auth ok 9 - 200 OK ok 10 - REMOTE_USER=Aladdin 1..10 ok -[2024-05-07 02:15:45.10832] [3173018] [trace] [M2Py8OCBwx_F] GET "/" -[2024-05-07 02:15:45.10882] [3173018] [trace] [M2Py8OCBwx_F] Routing to a callback -[2024-05-07 02:15:45.11090] [3173018] [debug] [CGI:CODE(0xaaaaf4740be0):3173022] START CODE(0xaaaaf4740be0) -[2024-05-07 02:15:45.12424] [3173018] [trace] [M2Py8OCBwx_F] 200 OK (0.015879s, 62.976/s) +[2025-06-09 08:39:25.66252] [380731] [trace] [ENfUVjRhxNTJ] GET "/" +[2025-06-09 08:39:25.66304] [380731] [trace] [ENfUVjRhxNTJ] Routing to a callback +[2025-06-09 08:39:25.66677] [380731] [debug] [CGI:CODE(0xaaaae473ed00):380824] START CODE(0xaaaae473ed00) +[2025-06-09 08:39:25.68817] [380731] [trace] [ENfUVjRhxNTJ] 200 OK (0.025613s, 39.043/s) t/run.t .......................... ok 1 - GET / ok 2 - 200 OK @@ -588,7 +624,7 @@ ok t/zombies.t ...................... skipped: (no reason given) All tests successful. -Files=18, Tests=110, 8 wallclock secs ( 0.07 usr 0.02 sys + 4.54 cusr 0.84 csys = 5.47 CPU) +Files=18, Tests=110, 10 wallclock secs ( 0.05 usr 0.04 sys + 4.58 cusr 0.92 csys = 5.59 CPU) Result: PASS make[1]: Leaving directory '/build/reproducible-path/libmojolicious-plugin-cgi-perl-0.40' create-stamp debian/debhelper-build-stamp @@ -621,12 +657,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/371255/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/371255/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/3169494 and its subdirectories -I: Current time: Mon May 6 14:15:48 -12 2024 -I: pbuilder-time-stamp: 1715048148 +I: removing directory /srv/workspace/pbuilder/371255 and its subdirectories +I: Current time: Mon Jun 9 22:39:29 +14 2025 +I: pbuilder-time-stamp: 1749458369