Diff of the two buildlogs: -- --- b1/build.log 2023-04-10 18:40:04.928742735 +0000 +++ b2/build.log 2023-04-10 18:42:46.811334515 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Apr 10 06:37:23 -12 2023 -I: pbuilder-time-stamp: 1681151843 +I: Current time: Mon May 13 15:03:07 +14 2024 +I: pbuilder-time-stamp: 1715562187 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./golang-github-labstack-echo_4.2.1.orig.tar.gz] I: copying [./golang-github-labstack-echo_4.2.1-3.debian.tar.xz] I: Extracting source -gpgv: Signature made Tue Nov 29 14:37:03 2022 -12 +gpgv: Signature made Wed Nov 30 16:37:03 2022 +14 gpgv: using RSA key B23862C415D6565A4E86CBD7579C160D4C9E23E8 gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./golang-github-labstack-echo_4.2.1-3.dsc: no acceptable signature found @@ -27,11 +27,20 @@ dpkg-source: info: applying disable-network-test.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/4723/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/27338/tmp/hooks/D01_modify_environment starting +debug: Running on codethink15-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 +lrwxrwxrwx 1 root root 4 Jan 6 2023 /bin/sh -> dash +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/27338/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/27338/tmp/hooks/D02_print_environment starting I: set BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' + 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=8' @@ -41,38 +50,38 @@ IFS=' ' LANG='C' - LANGUAGE='en_US:en' + LANGUAGE='nl_BE:nl' LC_ALL='C' MAIL='/var/mail/root' OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' + 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' - PPID='4723' + PPID='27338' PS1='# ' PS2='> ' 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.VJG2PMdU/pbuilderrc_BsKe --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.VJG2PMdU/b1 --logfile b1/build.log golang-github-labstack-echo_4.2.1-3.dsc' + 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.VJG2PMdU/pbuilderrc_76uj --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.VJG2PMdU/b2 --logfile b2/build.log --extrapackages usrmerge golang-github-labstack-echo_4.2.1-3.dsc' SUDO_GID='117' SUDO_UID='110' SUDO_USER='jenkins' TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' + TZ='/usr/share/zoneinfo/Etc/GMT-14' USER='root' USERNAME='root' _='/usr/bin/systemd-run' http_proxy='http://192.168.101.16:3128' I: uname -a - Linux codethink14-arm64 4.15.0-208-generic #220-Ubuntu SMP Mon Mar 20 14:28:12 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-208-generic #220-Ubuntu SMP Mon Mar 20 14:28:12 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 9 22:24 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/4723/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 13 06:47 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/27338/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -179,7 +188,7 @@ Get: 51 http://deb.debian.org/debian bookworm/main arm64 golang-golang-x-term-dev all 0.3.0-1 [14.6 kB] Get: 52 http://deb.debian.org/debian bookworm/main arm64 golang-golang-x-crypto-dev all 1:0.4.0-1 [1449 kB] Get: 53 http://deb.debian.org/debian bookworm/main arm64 golang-golang-x-time-dev all 0.3.0-1 [12.7 kB] -Fetched 100 MB in 3s (31.6 MB/s) +Fetched 100 MB in 5s (18.7 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 ... 19616 files and directories currently installed.) @@ -405,8 +414,17 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +usrmerge is already the newest version (35). +0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/golang-github-labstack-echo-4.2.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.2.1-3_source.changes +I: user script /srv/workspace/pbuilder/27338/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/27338/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +I: Running cd /build/golang-github-labstack-echo-4.2.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.2.1-3_source.changes dpkg-buildpackage: info: source package golang-github-labstack-echo dpkg-buildpackage: info: source version 4.2.1-3 dpkg-buildpackage: info: source distribution unstable @@ -425,29 +443,29 @@ 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 8 github.com/labstack/echo github.com/labstack/echo/middleware +internal/goarch internal/unsafeheader internal/goexperiment internal/goos internal/race +internal/cpu runtime/internal/syscall +internal/abi sync/atomic -unicode -internal/cpu -internal/goarch +runtime/internal/math +runtime/internal/sys unicode/utf8 +unicode internal/itoa container/list -internal/abi -runtime/internal/math -runtime/internal/sys math/bits -internal/bytealg -runtime/internal/atomic crypto/internal/subtle crypto/subtle crypto/internal/boring/sig unicode/utf16 vendor/golang.org/x/crypto/cryptobyte/asn1 +internal/bytealg +runtime/internal/atomic internal/nettrace vendor/golang.org/x/crypto/internal/subtle encoding @@ -462,11 +480,11 @@ runtime/cgo errors sort +io internal/oserror -internal/safefilepath path -io strconv +internal/safefilepath crypto/internal/nistec/fiat syscall vendor/golang.org/x/net/dns/dnsmessage @@ -480,22 +498,22 @@ golang.org/x/text/transform crypto reflect +bufio crypto/rc4 net/http/internal/ascii regexp/syntax html -bufio +time internal/syscall/execenv internal/syscall/unix -time regexp context +io/fs github.com/labstack/gommon/random internal/poll -io/fs embed -encoding/binary internal/fmtsort +encoding/binary os crypto/cipher crypto/internal/edwards25519/field @@ -504,84 +522,84 @@ crypto/internal/nistec vendor/golang.org/x/crypto/internal/poly1305 vendor/golang.org/x/crypto/curve25519/internal/field -encoding/pem golang.org/x/sys/unix +encoding/pem crypto/internal/edwards25519 crypto/internal/boring -vendor/golang.org/x/crypto/chacha20 crypto/des -crypto/sha512 +vendor/golang.org/x/crypto/chacha20 crypto/aes -fmt crypto/hmac +crypto/sha512 +fmt crypto/sha1 crypto/sha256 internal/godebug -internal/intern path/filepath -vendor/golang.org/x/crypto/chacha20poly1305 +internal/intern net/netip +vendor/golang.org/x/crypto/chacha20poly1305 vendor/golang.org/x/crypto/hkdf io/ioutil net encoding/hex net/url vendor/golang.org/x/crypto/curve25519 -encoding/json encoding/xml +encoding/json math/big github.com/valyala/fasttemplate compress/flate log -vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/text/unicode/bidi +vendor/golang.org/x/text/unicode/norm compress/gzip -vendor/golang.org/x/text/secure/bidirule -vendor/golang.org/x/net/http2/hpack github.com/mattn/go-isatty -mime +vendor/golang.org/x/net/http2/hpack github.com/mattn/go-colorable -mime/quotedprintable github.com/labstack/gommon/color +vendor/golang.org/x/text/secure/bidirule +mime +github.com/labstack/gommon/log +mime/quotedprintable net/http/internal runtime/debug golang.org/x/text/unicode/bidi -github.com/labstack/gommon/log golang.org/x/text/unicode/norm golang.org/x/net/http2/hpack flag runtime/trace github.com/labstack/gommon/bytes -golang.org/x/time/rate crypto/elliptic -vendor/golang.org/x/net/idna crypto/internal/boring/bbig encoding/asn1 -crypto/dsa crypto/rand -golang.org/x/text/secure/bidirule -text/template/parse -testing +crypto/dsa crypto/ed25519 crypto/rsa -crypto/x509/pkix +vendor/golang.org/x/net/idna +testing +golang.org/x/text/secure/bidirule +golang.org/x/time/rate +text/template/parse vendor/golang.org/x/crypto/cryptobyte -golang.org/x/net/idna +crypto/x509/pkix crypto/ecdsa +golang.org/x/net/idna text/template html/template vendor/golang.org/x/net/http/httpproxy net/textproto crypto/x509 vendor/golang.org/x/net/http/httpguts -golang.org/x/net/http/httpguts mime/multipart +golang.org/x/net/http/httpguts github.com/dgrijalva/jwt-go crypto/tls net/http/httptrace net/http -net/http/httputil golang.org/x/crypto/acme +net/http/httputil golang.org/x/net/http2 golang.org/x/crypto/acme/autocert golang.org/x/net/http2/h2c @@ -1153,7 +1171,7 @@ === RUN TestContext/empty_indent === RUN TestContext/empty_indent/json === RUN TestContext/empty_indent/xml ---- PASS: TestContext (0.00s) +--- PASS: TestContext (0.05s) --- PASS: TestContext/empty_indent (0.00s) --- PASS: TestContext/empty_indent/json (0.00s) --- PASS: TestContext/empty_indent/xml (0.00s) @@ -1172,7 +1190,7 @@ === RUN TestContextSetParamNamesShouldUpdateEchoMaxParam --- PASS: TestContextSetParamNamesShouldUpdateEchoMaxParam (0.00s) === RUN TestContextFormValue ---- PASS: TestContextFormValue (0.01s) +--- PASS: TestContextFormValue (0.00s) === RUN TestContextQueryParam --- PASS: TestContextQueryParam (0.00s) === RUN TestContextFormFile @@ -1297,7 +1315,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:39403 +⇨ http server started on [::]:42635 --- PASS: TestEchoStart (0.01s) === RUN TestEcho_StartTLS === RUN TestEcho_StartTLS/nok,_invalid_certFile @@ -1326,7 +1344,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ https server started on [::]:33421 +⇨ https server started on [::]:42821 === RUN TestEcho_StartAutoTLS/nok,_invalid_address ____ __ @@ -1351,7 +1369,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:41195 +⇨ http server started on [::]:44211 === RUN TestEcho_StartH2CServer/nok,_invalid_address ____ __ @@ -1383,7 +1401,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:42667 +⇨ http server started on [::]:34495 --- PASS: TestEchoClose (0.01s) === RUN TestEchoShutdown @@ -1395,7 +1413,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:33221 +⇨ http server started on [::]:40543 --- PASS: TestEchoShutdown (0.01s) === RUN TestEchoListenerNetwork === RUN TestEchoListenerNetwork/tcp_ipv4_address @@ -1442,9 +1460,9 @@ ____________________________________O/_______ O\ ⇨ http server started on [::1]:1323 ---- PASS: TestEchoListenerNetwork (0.03s) +--- PASS: TestEchoListenerNetwork (0.05s) --- PASS: TestEchoListenerNetwork/tcp_ipv4_address (0.01s) - --- PASS: TestEchoListenerNetwork/tcp_ipv6_address (0.01s) + --- PASS: TestEchoListenerNetwork/tcp_ipv6_address (0.02s) --- PASS: TestEchoListenerNetwork/tcp4_ipv4_address (0.01s) --- PASS: TestEchoListenerNetwork/tcp6_ipv6_address (0.01s) === RUN TestEchoListenerNetworkInvalid @@ -1470,7 +1488,7 @@ https://echo.labstack.com ____________________________________O/_______ O\ -⇨ http server started on [::]:39325 +⇨ http server started on [::]:35923 --- PASS: TestEcho_ListenerAddr (0.01s) === RUN TestEcho_StartServer === RUN TestEcho_StartServer/nok,_invalid_address @@ -1503,24 +1521,24 @@ === RUN TestGroupRouteMiddlewareWithMatchAny --- PASS: TestGroupRouteMiddlewareWithMatchAny (0.00s) === RUN TestExtractIP -=== RUN TestExtractIP/ExtractIPFromXFFHeader(default) +=== RUN TestExtractIP/ExtractIPDirect +=== RUN TestExtractIP/ExtractIPFromRealIPHeader(trust_direct-facing_proxy) +=== RUN TestExtractIP/ExtractIPFromXFFHeader(trust_only_direct-facing_proxy) === RUN TestExtractIP/ExtractIPFromXFFHeader(trust_everything) === RUN TestExtractIP/ExtractIPFromXFFHeader(trust_ipForXFF3External) -=== RUN TestExtractIP/ExtractIPDirect === RUN TestExtractIP/ExtractIPFromRealIPHeader(default) === RUN TestExtractIP/ExtractIPFromRealIPHeader(trust_only_direct-facing_proxy) -=== RUN TestExtractIP/ExtractIPFromRealIPHeader(trust_direct-facing_proxy) -=== RUN TestExtractIP/ExtractIPFromXFFHeader(trust_only_direct-facing_proxy) +=== RUN TestExtractIP/ExtractIPFromXFFHeader(default) === RUN TestExtractIP/ExtractIPFromXFFHeader(trust_direct-facing_proxy) --- PASS: TestExtractIP (0.00s) - --- PASS: TestExtractIP/ExtractIPFromXFFHeader(default) (0.00s) + --- PASS: TestExtractIP/ExtractIPDirect (0.00s) + --- PASS: TestExtractIP/ExtractIPFromRealIPHeader(trust_direct-facing_proxy) (0.00s) + --- PASS: TestExtractIP/ExtractIPFromXFFHeader(trust_only_direct-facing_proxy) (0.00s) --- PASS: TestExtractIP/ExtractIPFromXFFHeader(trust_everything) (0.00s) --- PASS: TestExtractIP/ExtractIPFromXFFHeader(trust_ipForXFF3External) (0.00s) - --- PASS: TestExtractIP/ExtractIPDirect (0.00s) --- PASS: TestExtractIP/ExtractIPFromRealIPHeader(default) (0.00s) --- PASS: TestExtractIP/ExtractIPFromRealIPHeader(trust_only_direct-facing_proxy) (0.00s) - --- PASS: TestExtractIP/ExtractIPFromRealIPHeader(trust_direct-facing_proxy) (0.00s) - --- PASS: TestExtractIP/ExtractIPFromXFFHeader(trust_only_direct-facing_proxy) (0.00s) + --- PASS: TestExtractIP/ExtractIPFromXFFHeader(default) (0.00s) --- PASS: TestExtractIP/ExtractIPFromXFFHeader(trust_direct-facing_proxy) (0.00s) === RUN TestResponse --- PASS: TestResponse (0.00s) @@ -2299,7 +2317,7 @@ === RUN ExampleValueBinder_CustomFunc --- PASS: ExampleValueBinder_CustomFunc (0.00s) PASS -ok github.com/labstack/echo 0.184s +ok github.com/labstack/echo 0.288s === RUN TestBasicAuth --- PASS: TestBasicAuth (0.00s) === RUN TestBodyDump @@ -2315,7 +2333,7 @@ === RUN TestGzipNoContent --- PASS: TestGzipNoContent (0.00s) === RUN TestGzipErrorReturned ---- PASS: TestGzipErrorReturned (0.00s) +--- PASS: TestGzipErrorReturned (0.02s) === RUN TestGzipErrorReturnedInvalidConfig --- PASS: TestGzipErrorReturnedInvalidConfig (0.00s) === RUN TestGzipWithStatic @@ -2343,16 +2361,16 @@ === RUN TestCSRFWithoutSameSiteMode --- PASS: TestCSRFWithoutSameSiteMode (0.00s) === RUN TestCSRFWithSameSiteDefaultMode -[_csrf=ueQ170RibYT6ygjwgSlYoVR0kluP8Npj; Expires=Tue, 11 Apr 2023 18:39:49 GMT] +[_csrf=HeJUZT6KWSg4nqT2KnVberqnQbHzCiyj; Expires=Tue, 14 May 2024 01:05:29 GMT] --- PASS: TestCSRFWithSameSiteDefaultMode (0.00s) === RUN TestDecompress --- PASS: TestDecompress (0.00s) === RUN TestDecompressDefaultConfig --- PASS: TestDecompressDefaultConfig (0.01s) === RUN TestCompressRequestWithoutDecompressMiddleware ---- PASS: TestCompressRequestWithoutDecompressMiddleware (0.00s) +--- PASS: TestCompressRequestWithoutDecompressMiddleware (0.01s) === RUN TestDecompressNoContent ---- PASS: TestDecompressNoContent (0.01s) +--- PASS: TestDecompressNoContent (0.00s) === RUN TestDecompressErrorReturned --- PASS: TestDecompressErrorReturned (0.00s) === RUN TestDecompressSkipper @@ -2362,16 +2380,16 @@ === RUN TestJWTRace --- PASS: TestJWTRace (0.00s) === RUN TestJWT ---- PASS: TestJWT (0.00s) +--- PASS: TestJWT (0.02s) === RUN TestJWTwithKID --- PASS: TestJWTwithKID (0.00s) === RUN TestKeyAuth --- PASS: TestKeyAuth (0.00s) === RUN TestLogger -{"time":"2023-04-10T06:39:49.349909558-12:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":200,"error":"","latency":7520,"latency_human":"7.52µs","bytes_in":0,"bytes_out":4} -{"time":"2023-04-10T06:39:49.349983398-12:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":307,"error":"","latency":4560,"latency_human":"4.56µs","bytes_in":0,"bytes_out":4} -{"time":"2023-04-10T06:39:49.350028578-12:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":404,"error":"","latency":3720,"latency_human":"3.72µs","bytes_in":0,"bytes_out":4} -{"time":"2023-04-10T06:39:49.350105598-12:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":500,"error":"error","latency":30680,"latency_human":"30.68µs","bytes_in":0,"bytes_out":36} +{"time":"2024-05-13T15:05:29.84086304+14:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":200,"error":"","latency":7480,"latency_human":"7.48µs","bytes_in":0,"bytes_out":4} +{"time":"2024-05-13T15:05:29.84094436+14:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":307,"error":"","latency":12000,"latency_human":"12µs","bytes_in":0,"bytes_out":4} +{"time":"2024-05-13T15:05:29.84098444+14:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":404,"error":"","latency":5300,"latency_human":"5.3µs","bytes_in":0,"bytes_out":4} +{"time":"2024-05-13T15:05:29.84108514+14:00","id":"","remote_ip":"192.0.2.1","host":"example.com","method":"GET","uri":"/","user_agent":"","status":500,"error":"error","latency":37800,"latency_human":"37.8µs","bytes_in":0,"bytes_out":36} --- PASS: TestLogger (0.00s) === RUN TestLoggerIPAddress --- PASS: TestLoggerIPAddress (0.00s) @@ -2402,9 +2420,9 @@ === RUN TestClientCancelConnectionResultsHTTPCode499 --- PASS: TestClientCancelConnectionResultsHTTPCode499 (0.01s) === RUN TestProxy ---- PASS: TestProxy (0.01s) +--- PASS: TestProxy (0.03s) === RUN TestProxyRealIPHeader ---- PASS: TestProxyRealIPHeader (0.01s) +--- PASS: TestProxyRealIPHeader (0.00s) === RUN TestProxyRewrite === RUN TestProxyRewrite//api/users === RUN TestProxyRewrite//js/main.js @@ -2413,13 +2431,13 @@ === RUN TestProxyRewrite//user/jill/order/T%2FcO4lW%2Ft%2FVp%2F === RUN TestProxyRewrite//api/new_users === RUN TestProxyRewrite//api/users?limit=10 ---- PASS: TestProxyRewrite (0.01s) +--- PASS: TestProxyRewrite (0.03s) --- PASS: TestProxyRewrite//api/users (0.00s) - --- PASS: TestProxyRewrite//js/main.js (0.00s) + --- PASS: TestProxyRewrite//js/main.js (0.01s) --- PASS: TestProxyRewrite//old (0.00s) --- PASS: TestProxyRewrite//users/jack/orders/1 (0.00s) - --- PASS: TestProxyRewrite//user/jill/order/T%2FcO4lW%2Ft%2FVp%2F (0.00s) - --- PASS: TestProxyRewrite//api/new_users (0.00s) + --- PASS: TestProxyRewrite//user/jill/order/T%2FcO4lW%2Ft%2FVp%2F (0.01s) + --- PASS: TestProxyRewrite//api/new_users (0.01s) --- PASS: TestProxyRewrite//api/users?limit=10 (0.00s) === RUN TestProxyRewriteRegex === RUN TestProxyRewriteRegex//unmatched @@ -2429,7 +2447,7 @@ === RUN TestProxyRewriteRegex//c/ignore1/test/this === RUN TestProxyRewriteRegex//x/ignore/test === RUN TestProxyRewriteRegex//y/foo/bar ---- 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) @@ -2480,24 +2498,24 @@ rate_limiter_test.go:342: Running testcase #23 => 5.06s --- PASS: TestRateLimiterMemoryStore_Allow (0.00s) === RUN TestRateLimiterMemoryStore_cleanupStaleVisitors -2023-04-10 06:39:49.411290521 -1200 -12 m=+0.106059112 +2024-05-13 15:05:29.93367148 +1400 +14 m=+0.162449961 --- PASS: TestRateLimiterMemoryStore_cleanupStaleVisitors (0.00s) === RUN TestNewRateLimiterMemoryStore --- PASS: TestNewRateLimiterMemoryStore (0.00s) === RUN TestRecover ---- PASS: TestRecover (0.00s) +--- PASS: TestRecover (0.01s) === RUN TestRecoverWithConfig_LogLevel === RUN TestRecoverWithConfig_LogLevel/DEBUG === RUN TestRecoverWithConfig_LogLevel/INFO === RUN TestRecoverWithConfig_LogLevel/WARN === RUN TestRecoverWithConfig_LogLevel/ERROR === RUN TestRecoverWithConfig_LogLevel/OFF ---- PASS: TestRecoverWithConfig_LogLevel (0.01s) - --- PASS: TestRecoverWithConfig_LogLevel/DEBUG (0.00s) +--- PASS: TestRecoverWithConfig_LogLevel (0.04s) + --- PASS: TestRecoverWithConfig_LogLevel/DEBUG (0.01s) --- PASS: TestRecoverWithConfig_LogLevel/INFO (0.00s) --- PASS: TestRecoverWithConfig_LogLevel/WARN (0.00s) - --- PASS: TestRecoverWithConfig_LogLevel/ERROR (0.00s) - --- PASS: TestRecoverWithConfig_LogLevel/OFF (0.00s) + --- PASS: TestRecoverWithConfig_LogLevel/ERROR (0.01s) + --- PASS: TestRecoverWithConfig_LogLevel/OFF (0.01s) === RUN TestRedirectHTTPSRedirect --- PASS: TestRedirectHTTPSRedirect (0.00s) === RUN TestHTTPSRedirectBehindTLSTerminationProxy @@ -2535,7 +2553,7 @@ === RUN TestEchoRewritePreMiddleware --- PASS: TestEchoRewritePreMiddleware (0.00s) === RUN TestRewriteWithConfigPreMiddleware_Issue1143 ---- PASS: TestRewriteWithConfigPreMiddleware_Issue1143 (0.01s) +--- PASS: TestRewriteWithConfigPreMiddleware_Issue1143 (0.03s) === RUN TestEchoRewriteWithCaret --- PASS: TestEchoRewriteWithCaret (0.00s) === RUN TestEchoRewriteWithRegexRules @@ -2604,7 +2622,7 @@ === RUN TestRemoveTrailingSlashWithConfig/http://localhost:1323/\\%5C////%5C\\\example.com/ === RUN TestRemoveTrailingSlashWithConfig/http://localhost:1323//example.com/ === RUN TestRemoveTrailingSlashWithConfig/http://localhost:1323/%5C%5C/ ---- PASS: TestRemoveTrailingSlashWithConfig (0.00s) +--- PASS: TestRemoveTrailingSlashWithConfig (0.01s) --- PASS: TestRemoveTrailingSlashWithConfig//remove-slash/ (0.00s) --- PASS: TestRemoveTrailingSlashWithConfig//remove-slash/?key=value (0.00s) --- PASS: TestRemoveTrailingSlashWithConfig// (0.00s) @@ -2667,26 +2685,26 @@ === RUN Test_matchSubdomain --- PASS: Test_matchSubdomain (0.00s) === CONT TestTimeoutSkipper -=== CONT TestTimeoutRecoversPanic --- PASS: TestTimeoutSkipper (0.00s) -=== CONT TestTimeoutDataRace -{"time":"2023-04-10T06:39:49.442257143-12:00","level":"-","prefix":"echo","file":"recover.go","line":"92","message":"[PANIC RECOVER] panic!!! goroutine 310 [running]:\ngithub.com/labstack/echo/middleware.RecoverWithConfig.func1.1.1()\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/recover.go:77 +0xd8\npanic({0x891ae0, 0xa16688})\n\t/usr/lib/go-1.19/src/runtime/panic.go:884 +0x20c\nnet/http.(*timeoutHandler).ServeHTTP(0x4000300100, {0xa1c5c8, 0x4000300080}, 0x40002fc100)\n\t/usr/lib/go-1.19/src/net/http/server.go:3412 +0x6f8\ngithub.com/labstack/echo/middleware.TimeoutWithConfig.func1.1({0xa23008, 0x40005fc1e0})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout.go:72 +0x1d4\ngithub.com/labstack/echo/middleware.RecoverWithConfig.func1.1({0xa23008, 0x40005fc1e0})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/recover.go:98 +0xd8\ngithub.com/labstack/echo.(*Echo).ServeHTTP(0x4000306240, {0xa1c5c8?, 0x4000300080}, 0x40002fc100)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/echo.go:646 +0x430\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.func2()\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:162 +0x30\ngithub.com/stretchr/testify/assert.didPanic(0x40002ccd00?)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1055 +0x7c\ngithub.com/stretchr/testify/assert.NotPanics({0xa18f80, 0x40002ccd00}, 0x4000520060, {0x0, 0x0, 0x0})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1126 +0x64\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic(0x40001f3810?)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:161 +0x39c\ntesting.tRunner(0x40002ccd00, 0x980890)\n\t/usr/lib/go-1.19/src/testing/testing.go:1446 +0x10c\ncreated by testing.(*T).Run\n\t/usr/lib/go-1.19/src/testing/testing.go:1493 +0x328\n\ngoroutine 1 [chan receive]:\ntesting.tRunner.func1()\n\t/usr/lib/go-1.19/src/testing/testing.go:1412 +0x448\ntesting.tRunner(0x40000e0b60, 0x40000d9cb8)\n\t/usr/lib/go-1.19/src/testing/testing.go:1452 +0x148\ntesting.runTests(0x40000b5c20?, {0xd00c60, 0x5c, 0x5c}, {0xca00000000000000?, 0xcae9164ecb59f61c?, 0xd54400?})\n\t/usr/lib/go-1.19/src/testing/testing.go:1844 +0x3e8\ntesting.(*M).Run(0x40000b5c20)\n\t/usr/lib/go-1.19/src/testing/testing.go:1726 +0x500\nmain.main()\n\t_testmain.go:241 +0x1e8\n\ngoroutine 120 [sleep]:\ntime.Sleep(0xf4240)\n\t/usr/lib/go-1.19/src/runtime/time.go:195 +0x120\ngithub.com/labstack/echo/middleware.TestTimeoutDataRace.func1({0xa23008, 0x40005fc280})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:185 +0x2c\ngithub.com/labstack/echo/middleware.echoHandlerFuncWrapper.ServeHTTP({{0xa23008, 0x40005fc280}, 0x40001f8180, 0x0, 0x40004caa80}, {0xa1c598?, 0x400018e070}, 0x40002fc400)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout.go:97 +0xb0\nnet/http.(*timeoutHandler).ServeHTTP.func1()\n\t/usr/lib/go-1.19/src/net/http/server.go:3407 +0x74\ncreated by net/http.(*timeoutHandler).ServeHTTP\n\t/usr/lib/go-1.19/src/net/http/server.go:3401 +0x284\n\ngoroutine 29 [select]:\nnet/http.(*persistConn).readLoop(0x400013f0e0)\n\t/usr/lib/go-1.19/src/net/http/transport.go:2213 +0xbf4\ncreated by net/http.(*Transport).dialConn\n\t/usr/lib/go-1.19/src/net/http/transport.go:1751 +0x11e8\n\ngoroutine 307 [semacquire]:\nsync.runtime_SemacquireMutex(0x400052e480?, 0x0?, 0x400006de08?)\n\t/usr/lib/go-1.19/src/runtime/sema.go:77 +0x24\nsync.(*Mutex).lockSlow(0x4000099f60)\n\t/usr/lib/go-1.19/src/sync/mutex.go:171 +0x178\nsync.(*Mutex).Lock(...)\n\t/usr/lib/go-1.19/src/sync/mutex.go:90\ntesting.(*chattyPrinter).Updatef(0x4000099f50?, {0x94cad3?, 0x1c?}, {0x9422ca?, 0xd?}, {0x400006de98?, 0x1?, 0x1?})\n\t/usr/lib/go-1.19/src/testing/testing.go:463 +0xb4\ntesting.(*T).Parallel(0x40002cc820)\n\t/usr/lib/go-1.19/src/testing/testing.go:1275 +0x1e8\ngithub.com/labstack/echo/middleware.TestTimeoutErrorOutInHandler(0x40002cc680?)\n\t/build/golang-github-labstack-echo\n"} ---- PASS: TestTimeoutRecoversPanic (0.00s) -=== CONT TestTimeoutWithErrorMessage ---- PASS: TestTimeoutDataRace (0.00s) +=== CONT TestTimeoutWithDefaultErrorMessage === CONT TestTimeoutTestRequestClone ---- PASS: TestTimeoutTestRequestClone (0.00s) === CONT TestTimeoutOnTimeoutRouteErrorHandler === CONT TestTimeoutErrorOutInHandler --- PASS: TestTimeoutErrorOutInHandler (0.00s) -=== CONT TestTimeoutWithDefaultErrorMessage +--- PASS: TestTimeoutTestRequestClone (0.00s) +=== CONT TestTimeoutDataRace +=== CONT TestTimeoutRecoversPanic +=== CONT TestTimeoutWithErrorMessage === CONT TestTimeoutWithTimeout0 ---- PASS: TestTimeoutWithTimeout0 (0.00s) ---- PASS: TestTimeoutWithDefaultErrorMessage (0.00s) ---- PASS: TestTimeoutWithErrorMessage (0.00s) ---- PASS: TestTimeoutOnTimeoutRouteErrorHandler (0.00s) +{"time":"2024-05-13T15:05:30.0475436+14:00","level":"-","prefix":"echo","file":"recover.go","line":"92","message":"[PANIC RECOVER] panic!!! goroutine 287 [running]:\ngithub.com/labstack/echo/middleware.RecoverWithConfig.func1.1.1()\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/recover.go:77 +0xd8\npanic({0x891ae0, 0xa16688})\n\t/usr/lib/go-1.19/src/runtime/panic.go:884 +0x20c\nnet/http.(*timeoutHandler).ServeHTTP(0x4000246140, {0xa1c5c8, 0x40002460c0}, 0x400047c300)\n\t/usr/lib/go-1.19/src/net/http/server.go:3412 +0x6f8\ngithub.com/labstack/echo/middleware.TimeoutWithConfig.func1.1({0xa23008, 0x4000222140})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout.go:72 +0x1d4\ngithub.com/labstack/echo/middleware.RecoverWithConfig.func1.1({0xa23008, 0x4000222140})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/recover.go:98 +0xd8\ngithub.com/labstack/echo.(*Echo).ServeHTTP(0x4000320240, {0xa1c5c8?, 0x40002460c0}, 0x400047c300)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/echo.go:646 +0x430\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic.func2()\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:162 +0x30\ngithub.com/stretchr/testify/assert.didPanic(0x400044fba0?)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1055 +0x7c\ngithub.com/stretchr/testify/assert.NotPanics({0xa18f80, 0x400044fba0}, 0x400007c140, {0x0, 0x0, 0x0})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/stretchr/testify/assert/assertions.go:1126 +0x64\ngithub.com/labstack/echo/middleware.TestTimeoutRecoversPanic(0x400044fa00?)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:161 +0x39c\ntesting.tRunner(0x400044fba0, 0x980890)\n\t/usr/lib/go-1.19/src/testing/testing.go:1446 +0x10c\ncreated by testing.(*T).Run\n\t/usr/lib/go-1.19/src/testing/testing.go:1493 +0x328\n\ngoroutine 1 [chan receive]:\ntesting.tRunner.func1()\n\t/usr/lib/go-1.19/src/testing/testing.go:1412 +0x448\ntesting.tRunner(0x40000e0b60, 0x40000d9cb8)\n\t/usr/lib/go-1.19/src/testing/testing.go:1452 +0x148\ntesting.runTests(0x40000b5c20?, {0xd00c60, 0x5c, 0x5c}, {0x2b00000000000000?, 0x2bcad7c0cdfaedda?, 0xd54400?})\n\t/usr/lib/go-1.19/src/testing/testing.go:1844 +0x3e8\ntesting.(*M).Run(0x40000b5c20)\n\t/usr/lib/go-1.19/src/testing/testing.go:1726 +0x500\nmain.main()\n\t_testmain.go:241 +0x1e8\n\ngoroutine 293 [chan receive]:\ngithub.com/labstack/echo/middleware.TestTimeoutWithDefaultErrorMessage.func1({0xa23008, 0x40001a9540})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout_test.go:245 +0x30\ngithub.com/labstack/echo/middleware.echoHandlerFuncWrapper.ServeHTTP({{0xa23008, 0x40001a9540}, 0x400013fd20, 0x0, 0x400007f8c0}, {0xa1c598?, 0x400010ebd0}, 0x4000171c00)\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout.go:97 +0xb0\nnet/http.(*timeoutHandler).ServeHTTP.func1()\n\t/usr/lib/go-1.19/src/net/http/server.go:3407 +0x74\ncreated by net/http.(*timeoutHandler).ServeHTTP\n\t/usr/lib/go-1.19/src/net/http/server.go:3401 +0x284\n\ngoroutine 88 [select]:\nnet/http.(*persistConn).readLoop(0x4000001c20)\n\t/usr/lib/go-1.19/src/net/http/transport.go:2213 +0xbf4\ncreated by net/http.(*Transport).dialConn\n\t/usr/lib/go-1.19/src/net/http/transport.go:1751 +0x11e8\n\ngoroutine 89 [select]:\nnet/http.(*persistConn).writeLoop(0x4000001c20)\n\t/usr/lib/go-1.19/src/net/http/transport.go:2392 +0xa0\ncreated by net/http.(*Transport).dialConn\n\t/usr/lib/go-1.19/src/net/http/transport.go:1752 +0x1234\n\ngoroutine 288 [select]:\nnet/http.(*timeoutHandler).ServeHTTP(0x4000475100, {0xa1c5c8, 0x4000475080}, 0x400040c200)\n\t/usr/lib/go-1.19/src/net/http/server.go:3410 +0x2dc\ngithub.com/labstack/echo/middleware.TimeoutWithConfig.func1.1({0xa23008, 0x40001a9900})\n\t/build/golang-github-labstack-echo-4.2.1/_build/src/github.com/labstack/echo/middleware/timeout.go:72 +0x1d4\ngithub.com/labstack/echo/middleware.TestTimeoutDataRace(0x400044fa00?)\n\t/build/golang-github-labstack-echo-4.2.1/\n"} +--- PASS: TestTimeoutRecoversPanic (0.02s) +--- PASS: TestTimeoutWithTimeout0 (0.02s) +--- PASS: TestTimeoutWithErrorMessage (0.02s) +--- PASS: TestTimeoutWithDefaultErrorMessage (0.02s) +--- PASS: TestTimeoutOnTimeoutRouteErrorHandler (0.02s) +--- PASS: TestTimeoutDataRace (0.02s) PASS -ok github.com/labstack/echo/middleware 0.164s +ok github.com/labstack/echo/middleware 0.296s create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=_build -O--buildsystem=golang dh_prep -O--builddirectory=_build -O--buildsystem=golang @@ -2713,12 +2731,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/27338/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/27338/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/4723 and its subdirectories -I: Current time: Mon Apr 10 06:40:03 -12 2023 -I: pbuilder-time-stamp: 1681152003 +I: removing directory /srv/workspace/pbuilder/27338 and its subdirectories +I: Current time: Mon May 13 15:05:45 +14 2024 +I: pbuilder-time-stamp: 1715562345