Diff of the two buildlogs: -- --- b1/build.log 2024-04-20 00:24:46.883450998 +0000 +++ b2/build.log 2024-04-20 00:25:44.309011824 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Apr 19 12:22:57 -12 2024 -I: pbuilder-time-stamp: 1713572577 +I: Current time: Fri May 23 20:47:49 +14 2025 +I: pbuilder-time-stamp: 1747982869 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -28,54 +28,86 @@ dpkg-source: info: applying 0001-Skip-asserting-package-name-in-TestEcho_OnAddRouteHa.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/7765/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/4856/tmp/hooks/D01_modify_environment starting +debug: Running on ionos6-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 May 23 06:47 /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/4856/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/4856/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='trixie' - 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=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='f788f2ead26247c8ab4ecca066c7ac38' - 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='7765' - PS1='# ' - PS2='> ' + INVOCATION_ID=2255ca9f6ce547c7ab7f8ae9c003437f + 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=4856 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.I0guHU21/pbuilderrc_2oW8 --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.I0guHU21/b1 --logfile b1/build.log golang-github-labstack-echo_4.11.1-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://78.137.99.97: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.I0guHU21/pbuilderrc_MubK --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.I0guHU21/b2 --logfile b2/build.log golang-github-labstack-echo_4.11.1-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://85.184.249.68:3128 I: uname -a - Linux ionos2-i386 6.1.0-20-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.85-1 (2024-04-11) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.85-1 (2024-04-11) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 19 11:24 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/7765/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 20 17:46 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/4856/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -182,7 +214,7 @@ Get: 51 http://deb.debian.org/debian trixie/main i386 golang-golang-x-crypto-dev all 1:0.21.0-1 [1671 kB] Get: 52 http://deb.debian.org/debian trixie/main i386 golang-golang-x-net-dev all 1:0.23.0+dfsg-1 [896 kB] Get: 53 http://deb.debian.org/debian trixie/main i386 golang-golang-x-time-dev all 0.5.0-1 [12.9 kB] -Fetched 68.3 MB in 2s (34.3 MB/s) +Fetched 68.3 MB in 2s (33.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 ... 19874 files and directories currently installed.) @@ -409,7 +441,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/golang-github-labstack-echo-4.11.1/ && 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 > ../golang-github-labstack-echo_4.11.1-2_source.changes +I: user script /srv/workspace/pbuilder/4856/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/4856/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-labstack-echo-4.11.1/ && 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 > ../golang-github-labstack-echo_4.11.1-2_source.changes dpkg-buildpackage: info: source package golang-github-labstack-echo dpkg-buildpackage: info: source version 4.11.1-2 dpkg-buildpackage: info: source distribution unstable @@ -427,159 +463,159 @@ dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang dh_auto_configure -O--builddirectory=_build -O--buildsystem=golang dh_auto_build -O--builddirectory=_build -O--buildsystem=golang - cd _build && go install -trimpath -v -p 11 github.com/labstack/echo github.com/labstack/echo/middleware -internal/goarch -internal/goos -internal/unsafeheader + cd _build && go install -trimpath -v -p 21 github.com/labstack/echo github.com/labstack/echo/middleware +math/bits +internal/itoa internal/godebugs -internal/coverage/rtcov +internal/unsafeheader +crypto/internal/boring/sig +cmp +internal/goos +unicode/utf16 internal/race -runtime/internal/atomic internal/cpu +crypto/internal/alias +internal/goarch runtime/internal/syscall -internal/goexperiment +internal/coverage/rtcov sync/atomic -unicode +vendor/golang.org/x/crypto/cryptobyte/asn1 +runtime/internal/atomic +internal/goexperiment unicode/utf8 -internal/chacha8rand -internal/abi -runtime/internal/math -runtime/internal/sys -internal/itoa container/list -crypto/internal/alias -math/bits -crypto/internal/boring/sig -cmp -unicode/utf16 -vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace vendor/golang.org/x/crypto/internal/alias +unicode encoding log/internal +internal/abi +runtime/internal/math +internal/chacha8rand +runtime/internal/sys slices -internal/bytealg math +internal/bytealg runtime crypto/subtle internal/reflectlite sync +internal/singleflight internal/testlog internal/bisect -internal/singleflight +runtime/cgo internal/godebug -sort errors -runtime/cgo -io +sort internal/oserror internal/safefilepath internal/intern -vendor/golang.org/x/net/dns/dnsmessage -crypto/internal/nistec/fiat path +io +vendor/golang.org/x/net/dns/dnsmessage math/rand -syscall strconv +crypto/internal/nistec/fiat +syscall hash -bytes crypto/internal/randutil +bytes strings hash/crc32 github.com/valyala/bytebufferpool crypto -bufio -time -reflect -net/http/internal/ascii -internal/syscall/execenv +crypto/rc4 net/netip -internal/syscall/unix +reflect vendor/golang.org/x/text/transform -crypto/rc4 golang.org/x/text/transform +net/http/internal/ascii +bufio regexp/syntax html -crypto/internal/nistec regexp +internal/syscall/unix +internal/syscall/execenv +time +crypto/internal/nistec +internal/fmtsort +encoding/binary context github.com/labstack/gommon/random -internal/poll io/fs -os -internal/fmtsort -encoding/binary -crypto/cipher -crypto/internal/edwards25519/field +internal/poll crypto/md5 -vendor/golang.org/x/crypto/internal/poly1305 +crypto/internal/edwards25519/field +crypto/cipher encoding/base64 +vendor/golang.org/x/crypto/internal/poly1305 golang.org/x/sys/unix encoding/pem crypto/internal/edwards25519 -vendor/golang.org/x/crypto/chacha20 crypto/internal/boring +vendor/golang.org/x/crypto/chacha20 crypto/des crypto/sha512 -crypto/ecdh -crypto/aes -crypto/sha1 crypto/hmac +crypto/ecdh crypto/sha256 +crypto/sha1 +crypto/aes vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/crypto/hkdf +os path/filepath fmt -vendor/golang.org/x/crypto/hkdf +net encoding/hex github.com/valyala/fasttemplate -net/url log +net/url +net/http/internal +text/template/parse +golang.org/x/time/rate +mime/quotedprintable encoding/xml +compress/flate +github.com/labstack/gommon/bytes +golang.org/x/net/http2/hpack +mime encoding/json +runtime/debug +vendor/golang.org/x/net/http2/hpack math/big vendor/golang.org/x/text/unicode/norm -compress/flate -vendor/golang.org/x/net/http2/hpack -mime +golang.org/x/text/unicode/norm vendor/golang.org/x/text/unicode/bidi -mime/quotedprintable -compress/gzip -net/http/internal -runtime/debug -net golang.org/x/text/unicode/bidi -golang.org/x/text/unicode/norm -vendor/golang.org/x/text/secure/bidirule -golang.org/x/net/http2/hpack -github.com/labstack/gommon/bytes -golang.org/x/time/rate -text/template/parse +compress/gzip +github.com/mattn/go-isatty golang.org/x/text/secure/bidirule +github.com/mattn/go-colorable +github.com/labstack/gommon/color +text/template +vendor/golang.org/x/text/secure/bidirule +github.com/labstack/gommon/log vendor/golang.org/x/net/idna +golang.org/x/crypto/vendor/golang.org/x/net/idna +golang.org/x/net/idna crypto/internal/boring/bbig crypto/elliptic +crypto/dsa crypto/internal/bigmod crypto/rand -crypto/dsa encoding/asn1 -text/template crypto/ed25519 -golang.org/x/net/idna -golang.org/x/crypto/vendor/golang.org/x/net/idna +html/template crypto/rsa -github.com/mattn/go-isatty -github.com/mattn/go-colorable -github.com/labstack/gommon/color -vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix -github.com/labstack/gommon/log -html/template +vendor/golang.org/x/crypto/cryptobyte crypto/ecdsa -net/textproto vendor/golang.org/x/net/http/httpproxy +net/textproto crypto/x509 -golang.org/x/net/http/httpguts vendor/golang.org/x/net/http/httpguts +golang.org/x/net/http/httpguts mime/multipart github.com/golang-jwt/jwt crypto/tls @@ -593,7 +629,7 @@ github.com/labstack/echo github.com/labstack/echo/middleware dh_auto_test -O--builddirectory=_build -O--buildsystem=golang - cd _build && go test -vet=off -v -p 11 github.com/labstack/echo github.com/labstack/echo/middleware + cd _build && go test -vet=off -v -p 21 github.com/labstack/echo github.com/labstack/echo/middleware === RUN TestToMultipleFields --- PASS: TestToMultipleFields (0.00s) === RUN TestBindJSON @@ -1312,7 +1348,7 @@ === RUN TestEcho_StaticFS/do_not_allow_directory_traversal_(backslash_-_windows_separator) === RUN TestEcho_StaticFS/do_not_allow_directory_traversal_(slash_-_unix_separator) === RUN TestEcho_StaticFS/open_redirect_vulnerability ---- PASS: TestEcho_StaticFS (0.01s) +--- PASS: TestEcho_StaticFS (0.00s) --- PASS: TestEcho_StaticFS/ok (0.00s) --- PASS: TestEcho_StaticFS/ok,_from_sub_fs (0.00s) --- PASS: TestEcho_StaticFS/No_file (0.00s) @@ -1384,7 +1420,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:42099 +⇨ http server started on [::]:34015 --- PASS: TestEchoStaticRedirectIndex (0.01s) === RUN TestEchoFile === RUN TestEchoFile/ok @@ -1486,7 +1522,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:44623 +⇨ http server started on [::]:45123 --- PASS: TestEchoStart (0.01s) === RUN TestEcho_StartTLS === RUN TestEcho_StartTLS/ok @@ -1499,7 +1535,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ https server started on [::]:41043 +⇨ https server started on [::]:46307 === RUN TestEcho_StartTLS/nok,_invalid_certFile === RUN TestEcho_StartTLS/nok,_invalid_keyFile === RUN TestEcho_StartTLS/nok,_failed_to_create_cert_out_of_certFile_and_keyFile @@ -1529,7 +1565,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ https server started on 127.0.0.1:41359 +⇨ https server started on 127.0.0.1:33131 ____ __ / __/___/ / ___ @@ -1539,13 +1575,13 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on 127.0.0.1:41581 ---- PASS: TestEchoStartTLSAndStart (0.22s) +⇨ http server started on 127.0.0.1:33181 +--- PASS: TestEchoStartTLSAndStart (0.17s) === RUN TestEchoStartTLSByteString === RUN TestEchoStartTLSByteString/ValidCertAndKeyFilePath -⇨ https server started on [::]:41293 +⇨ https server started on [::]:34531 === RUN TestEchoStartTLSByteString/ValidCertAndKeyByteString -⇨ https server started on [::]:39241 +⇨ https server started on [::]:45993 === RUN TestEchoStartTLSByteString/InvalidKeyType === RUN TestEchoStartTLSByteString/InvalidCertType === RUN TestEchoStartTLSByteString/InvalidCertAndKeyTypes @@ -1566,7 +1602,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ https server started on [::]:33011 +⇨ https server started on [::]:34589 === RUN TestEcho_StartAutoTLS/nok,_invalid_address ____ __ @@ -1591,7 +1627,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:44949 +⇨ http server started on [::]:35571 === RUN TestEcho_StartH2CServer/nok,_invalid_address ____ __ @@ -1653,7 +1689,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:34679 +⇨ http server started on [::]:34435 --- PASS: TestEchoClose (0.01s) === RUN TestEchoShutdown @@ -1665,7 +1701,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:44125 +⇨ http server started on [::]:41647 --- PASS: TestEchoShutdown (0.01s) === RUN TestEchoListenerNetwork === RUN TestEchoListenerNetwork/tcp_ipv4_address @@ -1768,7 +1804,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:45687 +⇨ http server started on [::]:44677 --- PASS: TestEcho_ListenerAddr (0.01s) === RUN TestEcho_TLSListenerAddr @@ -1780,7 +1816,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ https server started on [::]:39813 +⇨ https server started on [::]:36519 --- PASS: TestEcho_TLSListenerAddr (0.01s) === RUN TestEcho_StartServer === RUN TestEcho_StartServer/ok @@ -1793,7 +1829,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:41447 +⇨ http server started on [::]:34871 === RUN TestEcho_StartServer/ok,_start_with_TLS ____ __ @@ -1804,7 +1840,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ https server started on [::]:44901 +⇨ https server started on [::]:34999 === RUN TestEcho_StartServer/nok,_invalid_address ____ __ @@ -2557,7 +2593,7 @@ === RUN TestRouterGitHubAPI//user/keys#01 === RUN TestRouterGitHubAPI//user/keys/:id#01 === RUN TestRouterGitHubAPI//user/keys/:id#02 ---- PASS: TestRouterGitHubAPI (0.02s) +--- PASS: TestRouterGitHubAPI (0.01s) --- PASS: TestRouterGitHubAPI//authorizations (0.00s) --- PASS: TestRouterGitHubAPI//authorizations/:id (0.00s) --- PASS: TestRouterGitHubAPI//authorizations#01 (0.00s) @@ -2895,7 +2931,7 @@ === RUN ExampleValueBinder_CustomFunc --- PASS: ExampleValueBinder_CustomFunc (0.00s) PASS -ok github.com/labstack/echo 0.447s +ok github.com/labstack/echo 0.336s === RUN TestBasicAuth --- PASS: TestBasicAuth (0.00s) === RUN TestBodyDump @@ -2937,7 +2973,7 @@ === RUN TestGzipErrorReturnedInvalidConfig --- PASS: TestGzipErrorReturnedInvalidConfig (0.00s) === RUN TestGzipWithStatic ---- PASS: TestGzipWithStatic (0.02s) +--- PASS: TestGzipWithStatic (0.01s) === RUN TestContextTimeoutSkipper === PAUSE TestContextTimeoutSkipper === RUN TestContextTimeoutWithTimeout0 @@ -3187,7 +3223,7 @@ === RUN TestJWTConfig/Valid_JWT_with_an_invalid_key_using_a_user-defined_KeyFunc === RUN TestJWTConfig/Token_verification_does_not_pass_using_a_user-defined_KeyFunc === RUN TestJWTConfig/Valid_JWT_with_lower_case_AuthScheme ---- PASS: TestJWTConfig (0.01s) +--- PASS: TestJWTConfig (0.00s) --- PASS: TestJWTConfig/No_signing_key_provided (0.00s) --- PASS: TestJWTConfig/Unexpected_signing_method (0.00s) --- PASS: TestJWTConfig/Invalid_key (0.00s) @@ -3303,10 +3339,10 @@ --- PASS: TestKeyAuthWithConfig_ContinueOnIgnoredError/error_handler_is_called_for_missing_token (0.00s) --- PASS: TestKeyAuthWithConfig_ContinueOnIgnoredError/error_handler_is_called_for_invalid_token (0.00s) === RUN TestLogger -{"time":"2024-04-20T00:24:36.014626576Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":200,"error":"","latency":4185,"latency_human":"4.185µs","bytes_in":0,"bytes_out":4} -{"time":"2024-04-20T00:24:36.014701994Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":307,"error":"","latency":12271,"latency_human":"12.271µs","bytes_in":0,"bytes_out":4} -{"time":"2024-04-20T00:24:36.014736768Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":404,"error":"","latency":4459,"latency_human":"4.459µs","bytes_in":0,"bytes_out":4} -{"time":"2024-04-20T00:24:36.014782501Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":500,"error":"error","latency":10066,"latency_human":"10.066µs","bytes_in":0,"bytes_out":36} +{"time":"2025-05-23T06:48:37.25797616Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":200,"error":"","latency":2683,"latency_human":"2.683µs","bytes_in":0,"bytes_out":4} +{"time":"2025-05-23T06:48:37.258061835Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":307,"error":"","latency":2215,"latency_human":"2.215µs","bytes_in":0,"bytes_out":4} +{"time":"2025-05-23T06:48:37.258089293Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":404,"error":"","latency":1876,"latency_human":"1.876µs","bytes_in":0,"bytes_out":4} +{"time":"2025-05-23T06:48:37.258142424Z","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":500,"error":"error","latency":10196,"latency_human":"10.196µs","bytes_in":0,"bytes_out":36} --- PASS: TestLogger (0.00s) === RUN TestLoggerIPAddress --- PASS: TestLoggerIPAddress (0.00s) @@ -3375,7 +3411,7 @@ === RUN TestProxyRewriteRegex//x/ignore/test === RUN TestProxyRewriteRegex//y/foo/bar === RUN TestProxyRewriteRegex//y/foo/bar?q=1#frag ---- PASS: TestProxyRewriteRegex (0.01s) +--- PASS: TestProxyRewriteRegex (0.00s) --- PASS: TestProxyRewriteRegex//unmatched (0.00s) --- PASS: TestProxyRewriteRegex//a/test (0.00s) --- PASS: TestProxyRewriteRegex//b/foo/c/bar/baz (0.00s) @@ -3697,7 +3733,7 @@ === RUN TestStatic/ok,_do_not_serve_file,_when_a_handler_took_care_of_the_request === RUN TestStatic/nok,_when_html5_fail_if_the_index_file_does_not_exist === RUN TestStatic/ok,_serve_from_http.FileSystem ---- PASS: TestStatic (0.01s) +--- PASS: TestStatic (0.00s) --- PASS: TestStatic/ok,_serve_index_with_Echo_message (0.00s) --- PASS: TestStatic/ok,_serve_file_from_subdirectory (0.00s) --- PASS: TestStatic/ok,_when_html5_mode_serve_index_for_any_static_file_that_does_not_exist (0.00s) @@ -3778,7 +3814,7 @@ === RUN TestTimeoutWithFullEchoStack/404_-_write_response_in_global_error_handler === RUN TestTimeoutWithFullEchoStack/418_-_write_response_in_handler === RUN TestTimeoutWithFullEchoStack/503_-_handler_timeouts,_write_response_in_timeout_middleware ---- PASS: TestTimeoutWithFullEchoStack (0.19s) +--- PASS: TestTimeoutWithFullEchoStack (0.18s) --- PASS: TestTimeoutWithFullEchoStack/404_-_write_response_in_global_error_handler (0.01s) --- PASS: TestTimeoutWithFullEchoStack/418_-_write_response_in_handler (0.01s) --- PASS: TestTimeoutWithFullEchoStack/503_-_handler_timeouts,_write_response_in_timeout_middleware (0.16s) @@ -3787,44 +3823,44 @@ === RUN Test_matchSubdomain --- PASS: Test_matchSubdomain (0.00s) === CONT TestContextTimeoutSkipper -=== CONT TestTimeoutCanHandleContextDeadlineOnNextHandler === CONT TestTimeoutErrorOutInHandler -=== CONT TestTimeoutWithTimeout0 ---- PASS: TestTimeoutWithTimeout0 (0.00s) -=== CONT TestTimeoutSkipper ---- PASS: TestTimeoutErrorOutInHandler (0.00s) -=== CONT TestTimeoutWithDefaultErrorMessage === CONT TestContextTimeoutWithDefaultErrorMessage -=== CONT TestTimeoutWithErrorMessage ---- PASS: TestTimeoutSkipper (0.00s) +=== CONT TestContextTimeoutSuccessfulRequest +=== CONT TestContextTimeoutCanHandleContextDeadlineOnNextHandler === CONT TestContextTimeoutTestRequestClone === CONT TestTimeoutDataRace -=== CONT TestTimeoutRecoversPanic -=== CONT TestContextTimeoutSuccessfulRequest -=== CONT TestContextTimeoutErrorOutInHandler -{"time":"2024-04-20T00:24:37.276913534Z","level":"-","prefix":"echo","file":"recover.go","line":"120","message":"[PANIC RECOVER] panic!!! goroutine 853 [running]:\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.Recover.RecoverWithConfig.func3.1.1()\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/recover.go:100 +0x186\npanic({0x85d26a0, 0x872c600})\n\t/usr/lib/go-1.22/src/runtime/panic.go:770 +0x103\nnet/http.(*timeoutHandler).ServeHTTP(0xaee2740, {0x872fc38, 0xab2e390}, 0xab0eb48)\n\t/usr/lib/go-1.22/src/net/http/server.go:3601 +0x707\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.TimeoutWithConfig.TimeoutConfig.ToMiddleware.func4.1({0x8732adc, 0xa9fc300})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout.go:125 +0x1d0\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.Recover.RecoverWithConfig.func3.1({0x8732adc, 0xa9fc300})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/recover.go:131 +0xe7\ngithub.com/labstack/echo.(*Echo).ServeHTTP(0xaa4bb88, {0x872fb74, 0xaee26a0}, 0xab0eb48)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/echo.go:669 +0x326\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.func2()\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:197 +0x38\ngithub.com/stretchr/testify/assert.didPanic(0xa97c240)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1158 +0xa8\ngithub.com/stretchr/testify/assert.NotPanics({0x872d8f8, 0xaaa4208}, 0xa97c240, {0x0, 0x0, 0x0})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1229 +0x5e\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic(0xaaa4208)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:196 +0x4a4\ntesting.tRunner(0xaaa4208, 0x8691198)\n\t/usr/lib/go-1.22/src/testing/testing.go:1689 +0x125\ncreated by testing.(*T).Run in goroutine 1\n\t/usr/lib/go-1.22/src/testing/testing.go:1742 +0x3b9\n\ngoroutine 1 [chan receive]:\ntesting.tRunner.func1()\n\t/usr/lib/go-1.22/src/testing/testing.go:1650 +0x58b\ntesting.tRunner(0xa9a0008, 0xa985dfc)\n\t/usr/lib/go-1.22/src/testing/testing.go:1695 +0x15b\ntesting.runTests(0xa91c540, {0x8a84860, 0x9a, 0x9a}, {0xc180e146f8bae653, 0x8bb2e5b711, 0x8a86920})\n\t/usr/lib/go-1.22/src/testing/testing.go:2159 +0x39d\ntesting.(*M).Run(0xa922460)\n\t/usr/lib/go-1.22/src/testing/testing.go:2027 +0x6d8\nmain.main()\n\t_testmain.go:373 +0x141\n\ngoroutine 850 [runnable]:\ntesting.(*T).Parallel(0xa9a1f08)\n\t/usr/lib/go-1.22/src/testing/testing.go:1483 +0x24a\ngithub.com/labstack/echo/middleware.TestTimeoutSuccessfulRequest(0xa9a1f08)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:94 +0x2f\ntesting.tRunner(0xa9a1f08, 0x86911a0)\n\t/usr/lib/go-1.22/src/testing/testing.go:1689 +0x125\ncreated by testing.(*T).Run in goroutine 1\n\t/usr/lib/go-1.22/src/testing/testing.go:1742 +0x3b9\n\ngoroutine 74 [select]:\ngithub.com/labstack/echo/middleware.sleepWithContext({0x8730768, 0x8ab38a0}, 0x1312d00)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout_test.go:219 +0xf1\ngithub.com/labstack/echo/middleware.TestContextTimeoutSkipper.func2({0x8732adc, 0xabda180})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout_test.go:33 +0x64\ngithub.com/labstack/echo/middleware.ContextTimeoutWithConfig.ContextTimeoutConfig.ToMiddleware.func2.1({0x8732adc, 0xabda180})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout.go:58 +0x1bd\ngithub.com/labstack/echo/middleware.TestContextTimeoutSkipper(0xa9a0508)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack\n"} +--- PASS: TestTimeoutErrorOutInHandler (0.00s) === CONT TestTimeoutTestRequestClone -=== CONT TestContextTimeoutWithTimeout0 ---- PASS: TestContextTimeoutTestRequestClone (0.00s) --- PASS: TestContextTimeoutSuccessfulRequest (0.00s) ---- PASS: TestTimeoutCanHandleContextDeadlineOnNextHandler (0.00s) ---- PASS: TestTimeoutRecoversPanic (0.00s) ---- PASS: TestContextTimeoutErrorOutInHandler (0.00s) ---- PASS: TestTimeoutWithErrorMessage (0.00s) ---- PASS: TestContextTimeoutWithTimeout0 (0.00s) ---- PASS: TestTimeoutWithDefaultErrorMessage (0.00s) === CONT TestTimeoutOnTimeoutRouteErrorHandler ---- PASS: TestTimeoutDataRace (0.00s) +--- PASS: TestContextTimeoutTestRequestClone (0.00s) +=== CONT TestContextTimeoutErrorOutInHandler --- PASS: TestTimeoutTestRequestClone (0.00s) +=== CONT TestContextTimeoutWithTimeout0 +=== CONT TestTimeoutSkipper +=== CONT TestTimeoutWithTimeout0 === CONT TestTimeoutSuccessfulRequest -=== CONT TestContextTimeoutCanHandleContextDeadlineOnNextHandler ---- PASS: TestTimeoutSuccessfulRequest (0.00s) +=== CONT TestTimeoutWithDefaultErrorMessage +=== CONT TestTimeoutCanHandleContextDeadlineOnNextHandler +=== CONT TestTimeoutRecoversPanic +{"time":"2025-05-23T06:48:38.503555806Z","level":"-","prefix":"echo","file":"recover.go","line":"120","message":"[PANIC RECOVER] panic!!! goroutine 929 [running]:\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.Recover.RecoverWithConfig.func3.1.1()\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/recover.go:100 +0x186\npanic({0x85d26a0, 0x872c600})\n\t/usr/lib/go-1.22/src/runtime/panic.go:770 +0x103\nnet/http.(*timeoutHandler).ServeHTTP(0xa1545e0, {0x872fc38, 0xa3aa240}, 0xa1a4828)\n\t/usr/lib/go-1.22/src/net/http/server.go:3601 +0x707\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.TimeoutWithConfig.TimeoutConfig.ToMiddleware.func4.1({0x8732adc, 0x9e8c120})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout.go:125 +0x1d0\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.Recover.RecoverWithConfig.func3.1({0x8732adc, 0x9e8c120})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/recover.go:131 +0xe7\ngithub.com/labstack/echo.(*Echo).ServeHTTP(0xa03c2c8, {0x872fb74, 0xa154540}, 0xa1a4828)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/echo.go:669 +0x326\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.func2()\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:197 +0x38\ngithub.com/stretchr/testify/assert.didPanic(0x9f0e0f0)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1158 +0xa8\ngithub.com/stretchr/testify/assert.NotPanics({0x872d8f8, 0x9da6f08}, 0x9f0e0f0, {0x0, 0x0, 0x0})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1229 +0x5e\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic(0x9da6f08)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:196 +0x4a4\ntesting.tRunner(0x9da6f08, 0x8691198)\n\t/usr/lib/go-1.22/src/testing/testing.go:1689 +0x125\ncreated by testing.(*T).Run in goroutine 1\n\t/usr/lib/go-1.22/src/testing/testing.go:1742 +0x3b9\n\ngoroutine 1 [chan receive]:\ntesting.tRunner.func1()\n\t/usr/lib/go-1.22/src/testing/testing.go:1650 +0x58b\ntesting.tRunner(0x9da6008, 0x9d87dfc)\n\t/usr/lib/go-1.22/src/testing/testing.go:1695 +0x15b\ntesting.runTests(0x9d1c540, {0x8a84860, 0x9a, 0x9a}, {0xc20425074d218f0a, 0x8bb2daa205, 0x8a86920})\n\t/usr/lib/go-1.22/src/testing/testing.go:2159 +0x39d\ntesting.(*M).Run(0x9d22460)\n\t/usr/lib/go-1.22/src/testing/testing.go:2027 +0x6d8\nmain.main()\n\t_testmain.go:373 +0x141\n\ngoroutine 61 [select]:\ngithub.com/labstack/echo/middleware.sleepWithContext({0x8730768, 0x8ab38a0}, 0x1312d00)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout_test.go:219 +0xf1\ngithub.com/labstack/echo/middleware.TestContextTimeoutSkipper.func2({0x8732adc, 0xa132120})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout_test.go:33 +0x64\ngithub.com/labstack/echo/middleware.ContextTimeoutWithConfig.ContextTimeoutConfig.ToMiddleware.func2.1({0x8732adc, 0xa132120})\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout.go:58 +0x1bd\ngithub.com/labstack/echo/middleware.TestContextTimeoutSkipper(0x9da6508)\n\t/build/reproducible-path/golang-github-labstack-echo-4.11.1/_build/src/github.com/labstack/echo/middleware/context_timeout_test.go:38 +0x250\ntesting.tRunner(0x9da6508, 0x8690fd8)\n\t/usr/lib/go-1.22/src/testing/testing.go:1689 +0x125\ncreated by testing.(*T).Run in goroutine 1\n\t/usr/lib/go-1.22/src/testing/testing.go:1742 +0x3b9\n\ngoroutine 62 [sync.Mutex.Lock]:\nsync.runtime_SemacquireMutex(0x9da600c, 0x0, 0x1)\n\t/usr/lib/go-1.22/src/runtime/sema.go:77 +0x3f\nsync.(*Mutex).lockSlow(0x9da6008)\n\t/usr/lib/go-1.22/src/sync/mutex.go:171 +0x247\nsync.(*Mutex).Lock(0x9da6008)\n\t/usr/lib/go-1.22/src/sync/mut\n"} +=== CONT TestTimeoutWithErrorMessage +--- PASS: TestContextTimeoutErrorOutInHandler (0.00s) +--- PASS: TestContextTimeoutWithTimeout0 (0.00s) +--- PASS: TestTimeoutSkipper (0.00s) +--- PASS: TestTimeoutWithTimeout0 (0.00s) +--- PASS: TestTimeoutCanHandleContextDeadlineOnNextHandler (0.00s) +--- PASS: TestTimeoutDataRace (0.00s) +--- PASS: TestTimeoutWithDefaultErrorMessage (0.00s) +--- PASS: TestTimeoutRecoversPanic (0.00s) --- PASS: TestTimeoutOnTimeoutRouteErrorHandler (0.00s) +--- PASS: TestTimeoutSuccessfulRequest (0.00s) +--- PASS: TestTimeoutWithErrorMessage (0.00s) --- PASS: TestContextTimeoutWithDefaultErrorMessage (0.01s) --- PASS: TestContextTimeoutSkipper (0.02s) --- PASS: TestContextTimeoutCanHandleContextDeadlineOnNextHandler (0.05s) PASS -ok github.com/labstack/echo/middleware 1.385s +ok github.com/labstack/echo/middleware 1.338s create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=_build -O--buildsystem=golang dh_prep -O--builddirectory=_build -O--buildsystem=golang @@ -3851,12 +3887,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/4856/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/4856/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/7765 and its subdirectories -I: Current time: Fri Apr 19 12:24:46 -12 2024 -I: pbuilder-time-stamp: 1713572686 +I: removing directory /srv/workspace/pbuilder/4856 and its subdirectories +I: Current time: Fri May 23 20:48:43 +14 2025 +I: pbuilder-time-stamp: 1747982923