Diff of the two buildlogs: -- --- b1/build.log 2024-04-27 16:52:17.300508378 +0000 +++ b2/build.log 2024-04-27 16:53:40.536963659 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri May 30 11:12:30 -12 2025 -I: pbuilder-time-stamp: 1748646750 +I: Current time: Sat May 31 13:15:19 +14 2025 +I: pbuilder-time-stamp: 1748646919 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -26,52 +26,84 @@ dpkg-source: info: unpacking golang-github-grpc-ecosystem-go-grpc-middleware_1.3.0-2.debian.tar.xz I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/3987472/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/2872634/tmp/hooks/D01_modify_environment starting +debug: Running on codethink01-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 May 30 23:15 /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/2872634/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/2872634/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='trixie' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='be165bcab04a45e4b6a5bd08335a299d' - 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='3987472' - PS1='# ' - PS2='> ' + INVOCATION_ID=882ffcdd25b643b199c0836da63e942d + 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=2872634 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.QTGzulCS/pbuilderrc_Yhc6 --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.QTGzulCS/b1 --logfile b1/build.log golang-github-grpc-ecosystem-go-grpc-middleware_1.3.0-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.QTGzulCS/pbuilderrc_a999 --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.QTGzulCS/b2 --logfile b2/build.log golang-github-grpc-ecosystem-go-grpc-middleware_1.3.0-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 codethink03-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 24 13:38 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/3987472/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 26 17:47 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/2872634/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -320,7 +352,7 @@ Get: 187 http://deb.debian.org/debian trixie/main arm64 golang-gopkg-cheggaaa-pb.v1-dev all 1.0.25-3 [13.4 kB] Get: 188 http://deb.debian.org/debian trixie/main arm64 golang-k8s-sigs-yaml-dev all 1.3.0-1 [15.1 kB] Get: 189 http://deb.debian.org/debian trixie/main arm64 golang-etcd-server-dev all 3.4.30-1 [928 kB] -Fetched 167 MB in 2s (96.8 MB/s) +Fetched 167 MB in 1s (226 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package golang-golang-x-sys-dev. (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 ... 19937 files and directories currently installed.) @@ -1091,7 +1123,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/golang-github-grpc-ecosystem-go-grpc-middleware-1.3.0/ && 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-grpc-ecosystem-go-grpc-middleware_1.3.0-2_source.changes +I: user script /srv/workspace/pbuilder/2872634/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/2872634/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-grpc-ecosystem-go-grpc-middleware-1.3.0/ && 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-grpc-ecosystem-go-grpc-middleware_1.3.0-2_source.changes dpkg-buildpackage: info: source package golang-github-grpc-ecosystem-go-grpc-middleware dpkg-buildpackage: info: source version 1.3.0-2 dpkg-buildpackage: info: source distribution unstable @@ -1112,36 +1148,36 @@ dh_auto_build -O--buildsystem=golang cd obj-aarch64-linux-gnu && go install -trimpath -v -p 12 github.com/grpc-ecosystem/go-grpc-middleware github.com/grpc-ecosystem/go-grpc-middleware/auth github.com/grpc-ecosystem/go-grpc-middleware/logging github.com/grpc-ecosystem/go-grpc-middleware/logging/kit github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus github.com/grpc-ecosystem/go-grpc-middleware/logging/settable github.com/grpc-ecosystem/go-grpc-middleware/logging/zap github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap github.com/grpc-ecosystem/go-grpc-middleware/ratelimit github.com/grpc-ecosystem/go-grpc-middleware/recovery github.com/grpc-ecosystem/go-grpc-middleware/retry github.com/grpc-ecosystem/go-grpc-middleware/tags github.com/grpc-ecosystem/go-grpc-middleware/tags/logrus github.com/grpc-ecosystem/go-grpc-middleware/tags/zap github.com/grpc-ecosystem/go-grpc-middleware/testing github.com/grpc-ecosystem/go-grpc-middleware/testing/gogotestproto github.com/grpc-ecosystem/go-grpc-middleware/testing/testproto github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils github.com/grpc-ecosystem/go-grpc-middleware/util/metautils github.com/grpc-ecosystem/go-grpc-middleware/validator -internal/unsafeheader internal/coverage/rtcov +internal/goos internal/goarch internal/godebugs -internal/itoa +internal/unsafeheader +runtime/internal/syscall unicode/utf8 +internal/chacha8rand +runtime/internal/math +internal/itoa +internal/abi +runtime/internal/sys +sync/atomic +unicode +internal/cpu +internal/race internal/goexperiment math/bits -internal/goos -runtime/internal/syscall -sync/atomic cmp -internal/cpu encoding -internal/abi -internal/race -internal/chacha8rand -runtime/internal/math -runtime/internal/sys unicode/utf16 -unicode log/internal internal/nettrace container/list crypto/internal/alias crypto/subtle -math -slices crypto/internal/boring/sig vendor/golang.org/x/crypto/cryptobyte/asn1 +slices +math vendor/golang.org/x/crypto/internal/alias google.golang.org/grpc/serviceconfig internal/bytealg @@ -1151,140 +1187,141 @@ sync internal/testlog internal/bisect -internal/singleflight google.golang.org/grpc/internal/buffer +internal/singleflight google.golang.org/grpc/internal/grpcsync go.uber.org/zap/internal/pool runtime/cgo internal/godebug errors sort -internal/intern -math/rand -crypto/internal/nistec/fiat -strconv internal/oserror -vendor/golang.org/x/net/dns/dnsmessage -path internal/safefilepath +vendor/golang.org/x/net/dns/dnsmessage io +strconv +path +internal/intern syscall +crypto/internal/nistec/fiat bytes +math/rand hash crypto/internal/randutil -text/tabwriter strings +text/tabwriter reflect -net/netip hash/crc32 +net/netip crypto crypto/rc4 +vendor/golang.org/x/text/transform +golang.org/x/text/transform +bufio html regexp/syntax net/http/internal/ascii -google.golang.org/grpc/encoding net/http/internal/testcert -bufio -vendor/golang.org/x/text/transform -golang.org/x/text/transform -regexp +google.golang.org/grpc/encoding time internal/syscall/unix internal/syscall/execenv -context +regexp internal/fmtsort -go.uber.org/zap/buffer -github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils -internal/poll encoding/binary -google.golang.org/grpc/backoff +context io/fs -google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/keepalive -go.uber.org/zap/internal/bufferpool +internal/poll +google.golang.org/grpc/internal/grpcrand +go.uber.org/zap/buffer +github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils +google.golang.org/grpc/backoff google.golang.org/grpc/internal/backoff -go.uber.org/zap/internal/stacktrace -google.golang.org/grpc/tap +go.uber.org/zap/internal/bufferpool embed +google.golang.org/grpc/tap +go.uber.org/zap/internal/stacktrace encoding/base64 crypto/cipher crypto/internal/edwards25519/field crypto/md5 vendor/golang.org/x/crypto/internal/poly1305 -crypto/internal/nistec golang.org/x/sys/unix -encoding/pem +crypto/internal/nistec os +encoding/pem +crypto/internal/edwards25519 crypto/internal/boring crypto/des vendor/golang.org/x/crypto/chacha20 crypto/aes -crypto/sha512 crypto/hmac +crypto/sha512 crypto/sha1 crypto/sha256 -crypto/internal/edwards25519 vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/chacha20poly1305 crypto/ecdh +io/ioutil fmt path/filepath -io/ioutil google.golang.org/grpc/internal/envconfig -go.uber.org/zap/internal/exit internal/sysinfo +go.uber.org/zap/internal/exit net -compress/flate -encoding/json log +compress/flate net/url text/template/parse math/big -vendor/golang.org/x/net/http2/hpack encoding/hex vendor/golang.org/x/text/unicode/norm +vendor/golang.org/x/net/http2/hpack mime +encoding/json mime/quotedprintable -golang.org/x/net/internal/timeseries -vendor/golang.org/x/text/unicode/bidi net/http/internal google.golang.org/grpc/internal/grpclog -google.golang.org/grpc/attributes +golang.org/x/net/internal/timeseries +vendor/golang.org/x/text/unicode/bidi +compress/gzip google.golang.org/grpc/grpclog +google.golang.org/grpc/attributes google.golang.org/grpc/metadata google.golang.org/grpc/codes golang.org/x/text/unicode/bidi -golang.org/x/text/unicode/norm google.golang.org/grpc/internal/balancerload -golang.org/x/net/http2/hpack -compress/gzip +golang.org/x/text/unicode/norm google.golang.org/grpc/connectivity -vendor/golang.org/x/text/secure/bidirule +golang.org/x/net/http2/hpack github.com/grpc-ecosystem/go-grpc-middleware/util/metautils -text/template google.golang.org/grpc/internal github.com/go-logfmt/logfmt github.com/grpc-ecosystem/go-grpc-middleware/logging/settable +vendor/golang.org/x/text/secure/bidirule +text/template flag +golang.org/x/text/secure/bidirule go.uber.org/zap/internal/color github.com/davecgh/go-spew/spew +vendor/golang.org/x/net/idna github.com/pmezard/go-difflib/difflib gopkg.in/yaml.v3 runtime/debug +runtime/trace +github.com/opentracing/opentracing-go/log github.com/golang/protobuf/proto +go.uber.org/atomic github.com/go-kit/kit/log github.com/sirupsen/logrus -go.uber.org/atomic -golang.org/x/text/secure/bidirule -runtime/trace -golang.org/x/net/idna github.com/gogo/protobuf/proto testing -vendor/golang.org/x/net/idna +golang.org/x/net/idna go.uber.org/multierr github.com/go-kit/kit/log/level go.uber.org/zap/zapcore -github.com/opentracing/opentracing-go/log +html/template crypto/rand crypto/elliptic crypto/internal/bigmod @@ -1292,36 +1329,35 @@ encoding/asn1 crypto/ed25519 crypto/dsa -html/template crypto/rsa vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix -crypto/ecdsa go.uber.org/zap/internal +crypto/ecdsa google.golang.org/grpc/encoding/proto +github.com/golang/protobuf/ptypes/struct github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/duration github.com/golang/protobuf/ptypes/timestamp -github.com/golang/protobuf/ptypes/struct -google.golang.org/grpc/binarylog/grpc_binarylog_v1 -github.com/golang/protobuf/ptypes google.golang.org/genproto/googleapis/rpc/status +github.com/golang/protobuf/ptypes +google.golang.org/grpc/binarylog/grpc_binarylog_v1 github.com/golang/protobuf/jsonpb google.golang.org/grpc/internal/status google.golang.org/grpc/status github.com/grpc-ecosystem/go-grpc-middleware/logging -crypto/x509 -net/textproto +github.com/gogo/protobuf/protoc-gen-gogo/descriptor +github.com/gogo/protobuf/gogoproto +github.com/grpc-ecosystem/go-grpc-middleware/testing/gogotestproto vendor/golang.org/x/net/http/httpproxy google.golang.org/grpc/internal/syscall +net/textproto google.golang.org/grpc/stats +crypto/x509 vendor/golang.org/x/net/http/httpguts -mime/multipart golang.org/x/net/http/httpguts -github.com/gogo/protobuf/protoc-gen-gogo/descriptor -github.com/gogo/protobuf/gogoproto +mime/multipart crypto/tls -github.com/grpc-ecosystem/go-grpc-middleware/testing/gogotestproto net/http/httptrace google.golang.org/grpc/internal/credentials google.golang.org/grpc/credentials @@ -1329,45 +1365,45 @@ google.golang.org/grpc/resolver google.golang.org/grpc/peer google.golang.org/grpc/internal/channelz -google.golang.org/grpc/balancer -google.golang.org/grpc/internal/grpcutil google.golang.org/grpc/balancer/grpclb/state google.golang.org/grpc/internal/resolver/passthrough +google.golang.org/grpc/internal/grpcutil +google.golang.org/grpc/balancer google.golang.org/grpc/internal/resolver/dns +google.golang.org/grpc/internal/binarylog google.golang.org/grpc/balancer/base google.golang.org/grpc/internal/serviceconfig -google.golang.org/grpc/internal/binarylog google.golang.org/grpc/balancer/roundrobin golang.org/x/net/trace +net/http/httptest net/http/httputil -golang.org/x/net/http2 go.uber.org/zap -net/http/httptest github.com/opentracing/opentracing-go +golang.org/x/net/http2 github.com/opentracing/opentracing-go/ext github.com/stretchr/testify/assert github.com/stretchr/testify/require -google.golang.org/grpc/internal/transport github.com/stretchr/testify/suite +google.golang.org/grpc/internal/transport google.golang.org/grpc github.com/grpc-ecosystem/go-grpc-middleware github.com/grpc-ecosystem/go-grpc-middleware/ratelimit github.com/grpc-ecosystem/go-grpc-middleware/recovery -github.com/grpc-ecosystem/go-grpc-middleware/retry -github.com/grpc-ecosystem/go-grpc-middleware/testing/testproto github.com/grpc-ecosystem/go-grpc-middleware/validator +github.com/grpc-ecosystem/go-grpc-middleware/testing/testproto +github.com/grpc-ecosystem/go-grpc-middleware/retry github.com/grpc-ecosystem/go-grpc-middleware/auth github.com/grpc-ecosystem/go-grpc-middleware/tags +github.com/grpc-ecosystem/go-grpc-middleware/testing github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit -github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing -github.com/grpc-ecosystem/go-grpc-middleware/testing +github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus github.com/grpc-ecosystem/go-grpc-middleware/logging/kit -github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus -github.com/grpc-ecosystem/go-grpc-middleware/tags/logrus github.com/grpc-ecosystem/go-grpc-middleware/logging/zap github.com/grpc-ecosystem/go-grpc-middleware/tags/zap +github.com/grpc-ecosystem/go-grpc-middleware/tags/logrus +github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus dh_auto_test -O--buildsystem=golang cd obj-aarch64-linux-gnu && go test -vet=off -v -p 12 github.com/grpc-ecosystem/go-grpc-middleware github.com/grpc-ecosystem/go-grpc-middleware/auth github.com/grpc-ecosystem/go-grpc-middleware/logging github.com/grpc-ecosystem/go-grpc-middleware/logging/kit github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus github.com/grpc-ecosystem/go-grpc-middleware/logging/settable github.com/grpc-ecosystem/go-grpc-middleware/logging/zap github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap github.com/grpc-ecosystem/go-grpc-middleware/ratelimit github.com/grpc-ecosystem/go-grpc-middleware/recovery github.com/grpc-ecosystem/go-grpc-middleware/retry github.com/grpc-ecosystem/go-grpc-middleware/tags github.com/grpc-ecosystem/go-grpc-middleware/tags/logrus github.com/grpc-ecosystem/go-grpc-middleware/tags/zap github.com/grpc-ecosystem/go-grpc-middleware/testing github.com/grpc-ecosystem/go-grpc-middleware/testing/gogotestproto github.com/grpc-ecosystem/go-grpc-middleware/testing/testproto github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils github.com/grpc-ecosystem/go-grpc-middleware/util/metautils github.com/grpc-ecosystem/go-grpc-middleware/validator === RUN TestChainUnaryServer @@ -1381,7 +1417,7 @@ === RUN TestWrapServerStream --- PASS: TestWrapServerStream (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware 0.049s +ok github.com/grpc-ecosystem/go-grpc-middleware 0.040s ? github.com/grpc-ecosystem/go-grpc-middleware/logging [no test files] === RUN TestAuthFromMD --- PASS: TestAuthFromMD (0.00s) @@ -1395,8 +1431,8 @@ === RUN TestAuthTestSuite/TestUnary_PassesAuth === RUN TestAuthTestSuite/TestUnary_PassesWithPerRpcCredentials === NAME TestAuthTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33829 ---- PASS: TestAuthTestSuite (1.12s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39947 +--- PASS: TestAuthTestSuite (0.62s) --- PASS: TestAuthTestSuite/TestStream_BadAuth (0.00s) --- PASS: TestAuthTestSuite/TestStream_NoAuth (0.00s) --- PASS: TestAuthTestSuite/TestStream_PassesAuth (0.00s) @@ -1404,17 +1440,17 @@ --- PASS: TestAuthTestSuite/TestUnary_BadAuth (0.00s) --- PASS: TestAuthTestSuite/TestUnary_NoAuth (0.00s) --- PASS: TestAuthTestSuite/TestUnary_PassesAuth (0.00s) - --- PASS: TestAuthTestSuite/TestUnary_PassesWithPerRpcCredentials (0.04s) + --- PASS: TestAuthTestSuite/TestUnary_PassesWithPerRpcCredentials (0.01s) === RUN TestAuthOverrideTestSuite === RUN TestAuthOverrideTestSuite/TestStream_PassesAuth === RUN TestAuthOverrideTestSuite/TestUnary_PassesAuth === NAME TestAuthOverrideTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32867 ---- PASS: TestAuthOverrideTestSuite (0.39s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33209 +--- PASS: TestAuthOverrideTestSuite (0.26s) --- PASS: TestAuthOverrideTestSuite/TestStream_PassesAuth (0.00s) --- PASS: TestAuthOverrideTestSuite/TestUnary_PassesAuth (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/auth 1.595s +ok github.com/grpc-ecosystem/go-grpc-middleware/auth 0.926s ? github.com/grpc-ecosystem/go-grpc-middleware/tags/logrus [no test files] ? github.com/grpc-ecosystem/go-grpc-middleware/tags/zap [no test files] ? github.com/grpc-ecosystem/go-grpc-middleware/testing [no test files] @@ -1425,8 +1461,8 @@ === RUN TestKitClientSuite/TestPingError_WithCustomLevels === RUN TestKitClientSuite/TestPingList === NAME TestKitClientSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38179 ---- PASS: TestKitClientSuite (1.36s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32957 +--- PASS: TestKitClientSuite (0.26s) --- PASS: TestKitClientSuite/TestPing (0.00s) --- PASS: TestKitClientSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestKitClientSuite/TestPingList (0.00s) @@ -1434,8 +1470,8 @@ === RUN TestKitClientOverrideSuite/TestPingList_HasOverrides === RUN TestKitClientOverrideSuite/TestPing_HasOverrides === NAME TestKitClientOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35701 ---- PASS: TestKitClientOverrideSuite (2.22s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38037 +--- PASS: TestKitClientOverrideSuite (0.28s) --- PASS: TestKitClientOverrideSuite/TestPingList_HasOverrides (0.00s) --- PASS: TestKitClientOverrideSuite/TestPing_HasOverrides (0.00s) === RUN TestKitPayloadSuite @@ -1444,8 +1480,8 @@ === RUN TestKitPayloadSuite/TestPing_LogsBothRequestAndResponse payload_interceptors_test.go:81: map[grpc.method:Ping grpc.request.content:{"value":"something","sleepTimeMs":9999} grpc.service:mwitkow.testproto.TestService level:info span.kind:client system:grpc] === NAME TestKitPayloadSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44791 ---- PASS: TestKitPayloadSuite (0.68s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45325 +--- PASS: TestKitPayloadSuite (0.61s) --- PASS: TestKitPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.00s) --- PASS: TestKitPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.00s) --- PASS: TestKitPayloadSuite/TestPing_LogsBothRequestAndResponse (0.00s) @@ -1454,13 +1490,13 @@ === RUN TestKitLoggingSuite/TestPingList_WithCustomTags === RUN TestKitLoggingSuite/TestPing_WithCustomTags === NAME TestKitLoggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45239 + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39669 === RUN TestKitLoggingSuite/TestPingError_WithCustomLevels#01 === RUN TestKitLoggingSuite/TestPingList_WithCustomTags#01 === RUN TestKitLoggingSuite/TestPing_WithCustomTags#01 === NAME TestKitLoggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42037 ---- PASS: TestKitLoggingSuite (2.19s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43271 +--- PASS: TestKitLoggingSuite (0.79s) --- PASS: TestKitLoggingSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestKitLoggingSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestKitLoggingSuite/TestPing_WithCustomTags (0.00s) @@ -1471,8 +1507,8 @@ === RUN TestKitLoggingOverrideSuite/TestPingList_HasOverriddenDuration === RUN TestKitLoggingOverrideSuite/TestPing_HasOverriddenDuration === NAME TestKitLoggingOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46801 ---- PASS: TestKitLoggingOverrideSuite (1.03s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44537 +--- PASS: TestKitLoggingOverrideSuite (0.38s) --- PASS: TestKitLoggingOverrideSuite/TestPingList_HasOverriddenDuration (0.00s) --- PASS: TestKitLoggingOverrideSuite/TestPing_HasOverriddenDuration (0.00s) === RUN TestKitServerOverrideSuppressedSuite @@ -1480,16 +1516,16 @@ === RUN TestKitServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider === RUN TestKitServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider === NAME TestKitServerOverrideSuppressedSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40257 ---- PASS: TestKitServerOverrideSuppressedSuite (1.35s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42529 +--- PASS: TestKitServerOverrideSuppressedSuite (0.55s) --- PASS: TestKitServerOverrideSuppressedSuite/TestPingError_HasOverriddenDecider (0.00s) --- PASS: TestKitServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider (0.00s) --- PASS: TestKitServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit 8.898s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit 2.891s testing: warning: no tests to run PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit 0.081s [no tests to run] +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit 0.017s [no tests to run] === RUN Test_logrusGrpcLoggerV2_V === RUN Test_logrusGrpcLoggerV2_V/WarnLevel_setup_when_we_have_WarnLevel_msg_should_return_TRUE === RUN Test_logrusGrpcLoggerV2_V/WarnLevel_setup_when_we_have_ErrorLevel_msg_should_return_TRUE @@ -1513,8 +1549,8 @@ === RUN TestLogrusClientSuite/TestPingError_WithCustomLevels === RUN TestLogrusClientSuite/TestPingList === NAME TestLogrusClientSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42129 ---- PASS: TestLogrusClientSuite (1.59s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42297 +--- PASS: TestLogrusClientSuite (0.21s) --- PASS: TestLogrusClientSuite/TestPing (0.00s) --- PASS: TestLogrusClientSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestLogrusClientSuite/TestPingList (0.00s) @@ -1522,41 +1558,41 @@ === RUN TestLogrusClientOverrideSuite/TestPingList_HasOverrides === RUN TestLogrusClientOverrideSuite/TestPing_HasOverrides === NAME TestLogrusClientOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:41245 ---- PASS: TestLogrusClientOverrideSuite (0.58s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44105 +--- PASS: TestLogrusClientOverrideSuite (0.43s) --- PASS: TestLogrusClientOverrideSuite/TestPingList_HasOverrides (0.00s) --- PASS: TestLogrusClientOverrideSuite/TestPing_HasOverrides (0.00s) === RUN TestZapLoggingClientMessageProducerSuite === RUN TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer === NAME TestZapLoggingClientMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42109 ---- PASS: TestZapLoggingClientMessageProducerSuite (1.52s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37539 +--- PASS: TestZapLoggingClientMessageProducerSuite (0.26s) --- PASS: TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer (0.00s) === RUN TestLogrusPayloadSuite === RUN TestLogrusPayloadSuite/TestPingError_LogsOnlyRequestsOnError === RUN TestLogrusPayloadSuite/TestPingStream_LogsAllRequestsAndResponses === RUN TestLogrusPayloadSuite/TestPing_LogsBothRequestAndResponse === NAME TestLogrusPayloadSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43205 ---- PASS: TestLogrusPayloadSuite (1.12s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34393 +--- PASS: TestLogrusPayloadSuite (0.24s) --- PASS: TestLogrusPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.00s) - --- PASS: TestLogrusPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.00s) + --- PASS: TestLogrusPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.02s) --- PASS: TestLogrusPayloadSuite/TestPing_LogsBothRequestAndResponse (0.00s) === RUN TestLogrusServerSuite === RUN TestLogrusServerSuite/TestPingError_WithCustomLevels === RUN TestLogrusServerSuite/TestPingList_WithCustomTags === RUN TestLogrusServerSuite/TestPing_WithCustomTags === NAME TestLogrusServerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34787 + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33217 === RUN TestLogrusServerSuite/TestPingError_WithCustomLevels#01 === RUN TestLogrusServerSuite/TestPingList_WithCustomTags#01 === RUN TestLogrusServerSuite/TestPing_WithCustomTags#01 === NAME TestLogrusServerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:41121 ---- PASS: TestLogrusServerSuite (2.62s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:36083 +--- PASS: TestLogrusServerSuite (0.40s) --- PASS: TestLogrusServerSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestLogrusServerSuite/TestPingList_WithCustomTags (0.00s) - --- PASS: TestLogrusServerSuite/TestPing_WithCustomTags (0.01s) + --- PASS: TestLogrusServerSuite/TestPing_WithCustomTags (0.00s) --- PASS: TestLogrusServerSuite/TestPingError_WithCustomLevels#01 (0.00s) --- PASS: TestLogrusServerSuite/TestPingList_WithCustomTags#01 (0.00s) --- PASS: TestLogrusServerSuite/TestPing_WithCustomTags#01 (0.00s) @@ -1564,8 +1600,8 @@ === RUN TestLogrusServerOverrideSuite/TestPingList_HasOverriddenDuration === RUN TestLogrusServerOverrideSuite/TestPing_HasOverriddenDuration === NAME TestLogrusServerOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35669 ---- PASS: TestLogrusServerOverrideSuite (0.73s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39413 +--- PASS: TestLogrusServerOverrideSuite (0.46s) --- PASS: TestLogrusServerOverrideSuite/TestPingList_HasOverriddenDuration (0.00s) --- PASS: TestLogrusServerOverrideSuite/TestPing_HasOverriddenDuration (0.00s) === RUN TestLogrusServerOverrideDeciderSuite @@ -1573,26 +1609,26 @@ === RUN TestLogrusServerOverrideDeciderSuite/TestPingList_HasOverriddenDecider === RUN TestLogrusServerOverrideDeciderSuite/TestPing_HasOverriddenDecider === NAME TestLogrusServerOverrideDeciderSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34563 ---- PASS: TestLogrusServerOverrideDeciderSuite (0.46s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45419 +--- PASS: TestLogrusServerOverrideDeciderSuite (0.49s) --- PASS: TestLogrusServerOverrideDeciderSuite/TestPingError_HasOverriddenDecider (0.00s) --- PASS: TestLogrusServerOverrideDeciderSuite/TestPingList_HasOverriddenDecider (0.00s) --- PASS: TestLogrusServerOverrideDeciderSuite/TestPing_HasOverriddenDecider (0.00s) === RUN TestLogrusServerMessageProducerSuite === RUN TestLogrusServerMessageProducerSuite/TestPing_HasMessageProducer === NAME TestLogrusServerMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38739 ---- PASS: TestLogrusServerMessageProducerSuite (1.12s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39105 +--- PASS: TestLogrusServerMessageProducerSuite (0.22s) --- PASS: TestLogrusServerMessageProducerSuite/TestPing_HasMessageProducer (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus 9.785s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus 2.740s testing: warning: no tests to run PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus 0.049s [no tests to run] +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus 0.021s [no tests to run] === RUN TestSettableLoggerV2_init --- PASS: TestSettableLoggerV2_init (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/settable 0.065s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/settable 0.013s === RUN Test_zapGrpcLogger_V --- PASS: Test_zapGrpcLogger_V (0.00s) === RUN TestDurationToTimeMillisField @@ -1602,8 +1638,8 @@ === RUN TestZapClientSuite/TestPingError_WithCustomLevels === RUN TestZapClientSuite/TestPingList === NAME TestZapClientSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33653 ---- PASS: TestZapClientSuite (1.66s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45007 +--- PASS: TestZapClientSuite (0.63s) --- PASS: TestZapClientSuite/TestPing (0.00s) --- PASS: TestZapClientSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestZapClientSuite/TestPingList (0.00s) @@ -1611,51 +1647,51 @@ === RUN TestZapClientOverrideSuite/TestPingList_HasOverrides === RUN TestZapClientOverrideSuite/TestPing_HasOverrides === NAME TestZapClientOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33649 ---- PASS: TestZapClientOverrideSuite (0.51s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46003 +--- PASS: TestZapClientOverrideSuite (0.54s) --- PASS: TestZapClientOverrideSuite/TestPingList_HasOverrides (0.00s) --- PASS: TestZapClientOverrideSuite/TestPing_HasOverrides (0.00s) === RUN TestZapLoggingClientMessageProducerSuite === RUN TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer === NAME TestZapLoggingClientMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37319 ---- PASS: TestZapLoggingClientMessageProducerSuite (2.03s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40467 +--- PASS: TestZapLoggingClientMessageProducerSuite (0.43s) --- PASS: TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer (0.00s) === RUN TestZapPayloadSuite === RUN TestZapPayloadSuite/TestPingError_LogsOnlyRequestsOnError === RUN TestZapPayloadSuite/TestPingStream_LogsAllRequestsAndResponses === RUN TestZapPayloadSuite/TestPing_LogsBothRequestAndResponse - payload_interceptors_test.go:83: map[grpc.method:Ping grpc.request.content:map[msg:map[sleepTimeMs:9999 value:something]] grpc.service:mwitkow.testproto.TestService level:info msg:client request payload logged as grpc.request.content span.kind:client system:grpc ts:1.7486468979837103e+09] + payload_interceptors_test.go:83: map[grpc.method:Ping grpc.request.content:map[msg:map[sleepTimeMs:9999 value:something]] grpc.service:mwitkow.testproto.TestService level:info msg:client request payload logged as grpc.request.content span.kind:client system:grpc ts:1.74864698737327e+09] === NAME TestZapPayloadSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43877 ---- PASS: TestZapPayloadSuite (1.10s) - --- PASS: TestZapPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.01s) - --- PASS: TestZapPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.01s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38075 +--- PASS: TestZapPayloadSuite (0.42s) + --- PASS: TestZapPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.00s) + --- PASS: TestZapPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.00s) --- PASS: TestZapPayloadSuite/TestPing_LogsBothRequestAndResponse (0.00s) === RUN TestZapLoggingSuite === RUN TestZapLoggingSuite/TestPingError_WithCustomLevels === RUN TestZapLoggingSuite/TestPingList_WithCustomTags === RUN TestZapLoggingSuite/TestPing_WithCustomTags === NAME TestZapLoggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35433 + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45249 === RUN TestZapLoggingSuite/TestPingError_WithCustomLevels#01 === RUN TestZapLoggingSuite/TestPingList_WithCustomTags#01 === RUN TestZapLoggingSuite/TestPing_WithCustomTags#01 === NAME TestZapLoggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34245 ---- PASS: TestZapLoggingSuite (1.46s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39851 +--- PASS: TestZapLoggingSuite (1.09s) --- PASS: TestZapLoggingSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestZapLoggingSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestZapLoggingSuite/TestPing_WithCustomTags (0.00s) - --- PASS: TestZapLoggingSuite/TestPingError_WithCustomLevels#01 (0.01s) + --- PASS: TestZapLoggingSuite/TestPingError_WithCustomLevels#01 (0.00s) --- PASS: TestZapLoggingSuite/TestPingList_WithCustomTags#01 (0.00s) --- PASS: TestZapLoggingSuite/TestPing_WithCustomTags#01 (0.00s) === RUN TestZapLoggingOverrideSuite === RUN TestZapLoggingOverrideSuite/TestPingList_HasOverriddenDuration === RUN TestZapLoggingOverrideSuite/TestPing_HasOverriddenDuration === NAME TestZapLoggingOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38337 ---- PASS: TestZapLoggingOverrideSuite (0.77s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38235 +--- PASS: TestZapLoggingOverrideSuite (1.14s) --- PASS: TestZapLoggingOverrideSuite/TestPingList_HasOverriddenDuration (0.00s) --- PASS: TestZapLoggingOverrideSuite/TestPing_HasOverriddenDuration (0.00s) === RUN TestZapServerOverrideSuppressedSuite @@ -1663,31 +1699,31 @@ === RUN TestZapServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider === RUN TestZapServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider === NAME TestZapServerOverrideSuppressedSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37203 ---- PASS: TestZapServerOverrideSuppressedSuite (0.52s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37893 +--- PASS: TestZapServerOverrideSuppressedSuite (0.28s) --- PASS: TestZapServerOverrideSuppressedSuite/TestPingError_HasOverriddenDecider (0.00s) --- PASS: TestZapServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider (0.00s) --- PASS: TestZapServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider (0.00s) === RUN TestZapLoggingServerMessageProducerSuite === RUN TestZapLoggingServerMessageProducerSuite/TestPing_HasOverriddenMessageProducer === NAME TestZapLoggingServerMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46269 ---- PASS: TestZapLoggingServerMessageProducerSuite (0.46s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38001 +--- PASS: TestZapLoggingServerMessageProducerSuite (1.01s) --- PASS: TestZapLoggingServerMessageProducerSuite/TestPing_HasOverriddenMessageProducer (0.00s) === RUN TestSpecificLogging - logger.go:130: 2025-05-30T23:15:01.198Z INFO [Test specific log-line] {"system": "grpc", "grpc_log": true} + logger.go:130: 2025-05-30T23:16:30.900Z INFO [Test specific log-line] {"system": "grpc", "grpc_log": true} --- PASS: TestSpecificLogging (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap 8.605s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap 5.561s === RUN TestShorthands === RUN TestShorthands/debug - logger.go:130: 2025-05-30T23:14:52.663Z DEBUG omg! + logger.go:130: 2025-05-30T23:16:25.364Z DEBUG omg! === RUN TestShorthands/info - logger.go:130: 2025-05-30T23:14:52.664Z INFO omg! + logger.go:130: 2025-05-30T23:16:25.364Z INFO omg! === RUN TestShorthands/warn - logger.go:130: 2025-05-30T23:14:52.664Z WARN omg! + logger.go:130: 2025-05-30T23:16:25.365Z WARN omg! === RUN TestShorthands/error - logger.go:130: 2025-05-30T23:14:52.664Z ERROR omg! + logger.go:130: 2025-05-30T23:16:25.365Z ERROR omg! --- PASS: TestShorthands (0.00s) --- PASS: TestShorthands/debug (0.00s) --- PASS: TestShorthands/info (0.00s) @@ -1696,7 +1732,7 @@ === RUN TestShorthandsNoop --- PASS: TestShorthandsNoop (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap 0.069s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap 0.024s === RUN TestUnaryServerInterceptor_RateLimitPass --- PASS: TestUnaryServerInterceptor_RateLimitPass (0.00s) === RUN TestUnaryServerInterceptor_RateLimitFail @@ -1706,7 +1742,7 @@ === RUN TestStreamServerInterceptor_RateLimitFail --- PASS: TestStreamServerInterceptor_RateLimitFail (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/ratelimit 0.061s +ok github.com/grpc-ecosystem/go-grpc-middleware/ratelimit 0.028s === RUN TestRecoverySuite === RUN TestRecoverySuite/TestStream_NilPanickingReceive === RUN TestRecoverySuite/TestStream_PanickingReceive @@ -1715,8 +1751,8 @@ === RUN TestRecoverySuite/TestUnary_PanickingRequest === RUN TestRecoverySuite/TestUnary_SuccessfulRequest === NAME TestRecoverySuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40443 ---- PASS: TestRecoverySuite (1.49s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37233 +--- PASS: TestRecoverySuite (0.19s) --- PASS: TestRecoverySuite/TestStream_NilPanickingReceive (0.00s) --- PASS: TestRecoverySuite/TestStream_PanickingReceive (0.00s) --- PASS: TestRecoverySuite/TestStream_SuccessfulReceive (0.00s) @@ -1729,14 +1765,14 @@ === RUN TestRecoveryOverrideSuite/TestUnary_PanickingRequest === RUN TestRecoveryOverrideSuite/TestUnary_SuccessfulRequest === NAME TestRecoveryOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39225 ---- PASS: TestRecoveryOverrideSuite (3.43s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40155 +--- PASS: TestRecoveryOverrideSuite (1.33s) --- PASS: TestRecoveryOverrideSuite/TestStream_PanickingReceive (0.00s) --- PASS: TestRecoveryOverrideSuite/TestStream_SuccessfulReceive (0.00s) --- PASS: TestRecoveryOverrideSuite/TestUnary_PanickingRequest (0.00s) --- PASS: TestRecoveryOverrideSuite/TestUnary_SuccessfulRequest (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/recovery 5.015s +ok github.com/grpc-ecosystem/go-grpc-middleware/recovery 1.531s === RUN TestRetrySuite === RUN TestRetrySuite/TestCallOptionsDontPanicWithoutInterceptor === RUN TestRetrySuite/TestServerStream_CallFailsOnDeadlineExceeded @@ -1754,22 +1790,22 @@ === RUN TestRetrySuite/TestUnary_PerCallDeadline_Succeeds === RUN TestRetrySuite/TestUnary_SucceedsOnRetriableError === NAME TestRetrySuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37501 ---- PASS: TestRetrySuite (4.30s) - --- PASS: TestRetrySuite/TestCallOptionsDontPanicWithoutInterceptor (0.02s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38677 +--- PASS: TestRetrySuite (3.30s) + --- PASS: TestRetrySuite/TestCallOptionsDontPanicWithoutInterceptor (0.01s) --- PASS: TestRetrySuite/TestServerStream_CallFailsOnDeadlineExceeded (0.15s) --- PASS: TestRetrySuite/TestServerStream_CallFailsOnOutOfRetries (0.15s) - --- PASS: TestRetrySuite/TestServerStream_CallRetrySucceeds (0.75s) + --- PASS: TestRetrySuite/TestServerStream_CallRetrySucceeds (0.72s) --- PASS: TestRetrySuite/TestServerStream_FailsOnNonRetriableError (0.00s) --- PASS: TestRetrySuite/TestServerStream_OverrideFromContext (0.21s) --- PASS: TestRetrySuite/TestServerStream_PerCallDeadline_FailsOnParent (0.15s) - --- PASS: TestRetrySuite/TestServerStream_PerCallDeadline_Succeeds (0.42s) + --- PASS: TestRetrySuite/TestServerStream_PerCallDeadline_Succeeds (0.41s) --- PASS: TestRetrySuite/TestServerStream_SucceedsOnRetriableError (0.10s) --- PASS: TestRetrySuite/TestUnary_FailsOnNonRetriableContextError (0.00s) --- PASS: TestRetrySuite/TestUnary_FailsOnNonRetriableError (0.00s) --- PASS: TestRetrySuite/TestUnary_OverrideFromDialOpts (0.21s) --- PASS: TestRetrySuite/TestUnary_PerCallDeadline_FailsOnParent (0.15s) - --- PASS: TestRetrySuite/TestUnary_PerCallDeadline_Succeeds (0.24s) + --- PASS: TestRetrySuite/TestUnary_PerCallDeadline_Succeeds (0.23s) --- PASS: TestRetrySuite/TestUnary_SucceedsOnRetriableError (0.10s) === RUN TestChainedRetrySuite === RUN TestChainedRetrySuite/TestStreamWithChainedInterceptors_NoFailure @@ -1777,15 +1813,15 @@ === RUN TestChainedRetrySuite/TestUnaryWithChainedInterceptors_NoFailure === RUN TestChainedRetrySuite/TestUnaryWithChainedInterceptors_WithRetry === NAME TestChainedRetrySuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46015 ---- PASS: TestChainedRetrySuite (1.01s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37471 +--- PASS: TestChainedRetrySuite (0.28s) --- PASS: TestChainedRetrySuite/TestStreamWithChainedInterceptors_NoFailure (0.00s) - --- PASS: TestChainedRetrySuite/TestStreamWithChainedInterceptors_WithRetry (0.06s) + --- PASS: TestChainedRetrySuite/TestStreamWithChainedInterceptors_WithRetry (0.05s) --- PASS: TestChainedRetrySuite/TestUnaryWithChainedInterceptors_NoFailure (0.00s) - --- PASS: TestChainedRetrySuite/TestUnaryWithChainedInterceptors_WithRetry (0.05s) + --- PASS: TestChainedRetrySuite/TestUnaryWithChainedInterceptors_WithRetry (0.06s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/retry 5.385s -2025/05/30 23:14:55 proto: duplicate proto type registered: mwitkow.testproto.PingRequest +ok github.com/grpc-ecosystem/go-grpc-middleware/retry 3.607s +2025/05/30 23:16:25 proto: duplicate proto type registered: mwitkow.testproto.PingRequest === RUN TestCodeGenRequestLogFieldExtractor_ManualIsDeclared --- PASS: TestCodeGenRequestLogFieldExtractor_ManualIsDeclared (0.00s) === RUN TestTaggedRequestFiledExtractor_PingRequest @@ -1802,8 +1838,8 @@ === RUN TestTaggingSuite/TestPing_WithDeadline === RUN TestTaggingSuite/TestPing_WithNoDeadline === NAME TestTaggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35161 ---- PASS: TestTaggingSuite (2.66s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32815 +--- PASS: TestTaggingSuite (0.60s) --- PASS: TestTaggingSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestTaggingSuite/TestPing_WithCustomTags (0.00s) --- PASS: TestTaggingSuite/TestPing_WithDeadline (0.00s) @@ -1815,37 +1851,37 @@ === RUN TestTaggingOnInitialRequestSuite/TestPing_WithDeadline === RUN TestTaggingOnInitialRequestSuite/TestPing_WithNoDeadline === NAME TestTaggingOnInitialRequestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35037 ---- PASS: TestTaggingOnInitialRequestSuite (0.43s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35747 +--- PASS: TestTaggingOnInitialRequestSuite (0.87s) --- PASS: TestTaggingOnInitialRequestSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestTaggingOnInitialRequestSuite/TestPingStream_WithCustomTagsFirstRequest (0.00s) --- PASS: TestTaggingOnInitialRequestSuite/TestPing_WithCustomTags (0.00s) --- PASS: TestTaggingOnInitialRequestSuite/TestPing_WithDeadline (0.00s) --- PASS: TestTaggingOnInitialRequestSuite/TestPing_WithNoDeadline (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/tags 3.210s +ok github.com/grpc-ecosystem/go-grpc-middleware/tags 1.497s === RUN TestTagsCarrier_Set_JaegerTraceFormat --- PASS: TestTagsCarrier_Set_JaegerTraceFormat (0.00s) === RUN TestTaggingSuite === RUN TestTaggingSuite/TestPingEmpty_NotSampleTraces === RUN TestTaggingSuite/TestPingError_PropagatesTraces interceptors_test.go:170: span: traceId=1337, spanId=50, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:170: span: traceId=1337, spanId=54, parentId=52, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true grpc.request.value:something peer.address:127.0.0.1:37740 span.kind:server trace.sampled:true trace.spanid:54 trace.traceid:1337] + interceptors_test.go:170: span: traceId=1337, spanId=54, parentId=52, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true grpc.request.value:something peer.address:127.0.0.1:56726 span.kind:server trace.sampled:true trace.spanid:54 trace.traceid:1337] interceptors_test.go:170: span: traceId=1337, spanId=52, parentId=50, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true span.kind:client] === RUN TestTaggingSuite/TestPingList_PropagatesTraces interceptors_test.go:170: span: traceId=1337, spanId=56, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] interceptors_test.go:170: span: traceId=1337, spanId=58, parentId=56, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC span.kind:client] - interceptors_test.go:170: span: traceId=1337, spanId=60, parentId=58, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:37740 span.kind:server trace.sampled:true trace.spanid:60 trace.traceid:1337] + interceptors_test.go:170: span: traceId=1337, spanId=60, parentId=58, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:56726 span.kind:server trace.sampled:true trace.spanid:60 trace.traceid:1337] === RUN TestTaggingSuite/TestPing_ClientContextTags === RUN TestTaggingSuite/TestPing_CustomOpName === RUN TestTaggingSuite/TestPing_PropagatesTraces interceptors_test.go:170: span: traceId=1337, spanId=74, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:170: span: traceId=1337, spanId=78, parentId=76, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:37740 span.kind:server trace.sampled:true trace.spanid:78 trace.traceid:1337] + interceptors_test.go:170: span: traceId=1337, spanId=78, parentId=76, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:56726 span.kind:server trace.sampled:true trace.spanid:78 trace.traceid:1337] interceptors_test.go:170: span: traceId=1337, spanId=76, parentId=74, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC span.kind:client] === RUN TestTaggingSuite/TestPing_WithUnaryRequestHandlerFunc === NAME TestTaggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32999 ---- PASS: TestTaggingSuite (2.30s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:41341 +--- PASS: TestTaggingSuite (0.83s) --- PASS: TestTaggingSuite/TestPingEmpty_NotSampleTraces (0.00s) --- PASS: TestTaggingSuite/TestPingError_PropagatesTraces (0.00s) --- PASS: TestTaggingSuite/TestPingList_PropagatesTraces (0.00s) @@ -1857,22 +1893,22 @@ === RUN TestTaggingSuiteJaeger/TestPingEmpty_NotSampleTraces === RUN TestTaggingSuiteJaeger/TestPingError_PropagatesTraces interceptors_test.go:170: span: traceId=1337, spanId=92, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:170: span: traceId=1337, spanId=96, parentId=94, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true grpc.request.value:something peer.address:127.0.0.1:38596 span.kind:server trace.sampled:true trace.spanid:96 trace.traceid:1337] + interceptors_test.go:170: span: traceId=1337, spanId=96, parentId=94, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true grpc.request.value:something peer.address:127.0.0.1:44384 span.kind:server trace.sampled:true trace.spanid:96 trace.traceid:1337] interceptors_test.go:170: span: traceId=1337, spanId=94, parentId=92, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true span.kind:client] === RUN TestTaggingSuiteJaeger/TestPingList_PropagatesTraces interceptors_test.go:170: span: traceId=1337, spanId=98, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] interceptors_test.go:170: span: traceId=1337, spanId=100, parentId=98, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC span.kind:client] - interceptors_test.go:170: span: traceId=1337, spanId=102, parentId=100, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:38596 span.kind:server trace.sampled:true trace.spanid:102 trace.traceid:1337] + interceptors_test.go:170: span: traceId=1337, spanId=102, parentId=100, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:44384 span.kind:server trace.sampled:true trace.spanid:102 trace.traceid:1337] === RUN TestTaggingSuiteJaeger/TestPing_ClientContextTags === RUN TestTaggingSuiteJaeger/TestPing_CustomOpName === RUN TestTaggingSuiteJaeger/TestPing_PropagatesTraces interceptors_test.go:170: span: traceId=1337, spanId=116, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:170: span: traceId=1337, spanId=120, parentId=118, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:38596 span.kind:server trace.sampled:true trace.spanid:120 trace.traceid:1337] + interceptors_test.go:170: span: traceId=1337, spanId=120, parentId=118, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:44384 span.kind:server trace.sampled:true trace.spanid:120 trace.traceid:1337] interceptors_test.go:170: span: traceId=1337, spanId=118, parentId=116, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC span.kind:client] === RUN TestTaggingSuiteJaeger/TestPing_WithUnaryRequestHandlerFunc === NAME TestTaggingSuiteJaeger - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45957 ---- PASS: TestTaggingSuiteJaeger (1.76s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42141 +--- PASS: TestTaggingSuiteJaeger (0.40s) --- PASS: TestTaggingSuiteJaeger/TestPingEmpty_NotSampleTraces (0.00s) --- PASS: TestTaggingSuiteJaeger/TestPingError_PropagatesTraces (0.00s) --- PASS: TestTaggingSuiteJaeger/TestPingList_PropagatesTraces (0.00s) @@ -1881,11 +1917,11 @@ --- PASS: TestTaggingSuiteJaeger/TestPing_PropagatesTraces (0.00s) --- PASS: TestTaggingSuiteJaeger/TestPing_WithUnaryRequestHandlerFunc (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing 4.143s +ok github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing 1.252s === RUN TestJitterUp --- PASS: TestJitterUp (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils 0.067s +ok github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils 0.014s === RUN TestNiceMD_Get --- PASS: TestNiceMD_Get (0.00s) === RUN TestNiceMD_Del @@ -1903,9 +1939,9 @@ === RUN TestNiceMD_ToIncoming --- PASS: TestNiceMD_ToIncoming (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/util/metautils 0.032s +ok github.com/grpc-ecosystem/go-grpc-middleware/util/metautils 0.014s === RUN TestValidateWrapper ---- PASS: TestValidateWrapper (0.01s) +--- PASS: TestValidateWrapper (0.00s) === RUN TestValidatorTestSuite === RUN TestValidatorTestSuite/TestInvalidErrors_BidiStream === RUN TestValidatorTestSuite/TestInvalidErrors_ServerStream @@ -1913,12 +1949,12 @@ === RUN TestValidatorTestSuite/TestValidPasses_ServerStream === RUN TestValidatorTestSuite/TestValidPasses_Unary === NAME TestValidatorTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45037 + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46845 === RUN TestValidatorTestSuite/TestInvalidErrors_Unary#01 === RUN TestValidatorTestSuite/TestValidPasses_Unary#01 === NAME TestValidatorTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35957 ---- PASS: TestValidatorTestSuite (2.15s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37749 +--- PASS: TestValidatorTestSuite (0.93s) --- PASS: TestValidatorTestSuite/TestInvalidErrors_BidiStream (0.00s) --- PASS: TestValidatorTestSuite/TestInvalidErrors_ServerStream (0.00s) --- PASS: TestValidatorTestSuite/TestInvalidErrors_Unary (0.00s) @@ -1927,7 +1963,7 @@ --- PASS: TestValidatorTestSuite/TestInvalidErrors_Unary#01 (0.00s) --- PASS: TestValidatorTestSuite/TestValidPasses_Unary#01 (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/validator 2.233s +ok github.com/grpc-ecosystem/go-grpc-middleware/validator 0.948s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1954,12 +1990,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/2872634/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/2872634/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/3987472 and its subdirectories -I: Current time: Fri May 30 11:15:15 -12 2025 -I: pbuilder-time-stamp: 1748646915 +I: removing directory /srv/workspace/pbuilder/2872634 and its subdirectories +I: Current time: Sat May 31 13:16:38 +14 2025 +I: pbuilder-time-stamp: 1748646998