Diff of the two buildlogs: -- --- b1/build.log 2021-06-04 05:30:25.115207996 +0000 +++ b2/build.log 2021-06-04 05:32:01.211468970 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Thu Jun 3 17:23:51 -12 2021 -I: pbuilder-time-stamp: 1622784231 +I: Current time: Fri Jul 8 01:53:25 +14 2022 +I: pbuilder-time-stamp: 1657194805 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -17,8 +17,8 @@ I: copying [./golang-github-grpc-ecosystem-go-grpc-middleware_1.2.2-2.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.Tg8owrdx/trustedkeys.kbx': General error -gpgv: Signature made Fri Oct 23 19:50:15 2020 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.SZfjsvwO/trustedkeys.kbx': General error +gpgv: Signature made Sat Oct 24 21:50:15 2020 +14 gpgv: using EDDSA key E25DCF798D442B8EDF47814DC4854A3818E0B016 gpgv: issuer "zhsj@debian.org" gpgv: Can't check signature: No public key @@ -28,137 +28,171 @@ dpkg-source: info: unpacking golang-github-grpc-ecosystem-go-grpc-middleware_1.2.2-2.debian.tar.xz I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/8907/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/1527/tmp/hooks/D01_modify_environment starting +debug: Running on ionos16-i386. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +Removing 'diversion of /bin/sh to /bin/sh.distrib by dash' +Adding 'diversion of /bin/sh to /bin/sh.distrib by bash' +Removing 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by dash' +Adding 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by 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/1527/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/1527/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' - BUILD_ARCH='i386' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=10' - DISTRIBUTION='' - HOME='/root' - HOST_ARCH='i386' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="1" [2]="4" [3]="1" [4]="release" [5]="i686-pc-linux-gnu") + BASH_VERSION='5.1.4(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=i386 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=18' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='2ba02e94bb2b48918d235989025d8fe7' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - LD_LIBRARY_PATH='/usr/lib/libeatmydata' - LD_PRELOAD='libeatmydata.so' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='8907' - PS1='# ' - PS2='> ' + INVOCATION_ID=effff59d61c04d8db6a1a23218f021a4 + LANG=C + LANGUAGE=de_CH:de + LC_ALL=C + LD_LIBRARY_PATH=/usr/lib/libeatmydata + LD_PRELOAD=libeatmydata.so + MACHTYPE=i686-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=1527 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/tmp.QXLfTCRnlz/pbuilderrc_j1St --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.QXLfTCRnlz/b1 --logfile b1/build.log golang-github-grpc-ecosystem-go-grpc-middleware_1.2.2-2.dsc' - SUDO_GID='112' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://78.137.99.97:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.QXLfTCRnlz/pbuilderrc_A9mN --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.QXLfTCRnlz/b2 --logfile b2/build.log --extrapackages usrmerge golang-github-grpc-ecosystem-go-grpc-middleware_1.2.2-2.dsc' + SUDO_GID=112 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://85.184.249.68:3128 I: uname -a - Linux ionos12-i386 4.19.0-16-686-pae #1 SMP Debian 4.19.181-1 (2021-03-19) i686 GNU/Linux + Linux i-capture-the-hostname 4.19.0-16-amd64 #1 SMP Debian 4.19.181-1 (2021-03-19) x86_64 GNU/Linux I: ls -l /bin - total 5792 - -rwxr-xr-x 1 root root 1367848 Feb 24 08:57 bash - -rwxr-xr-x 3 root root 38280 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 38280 Jul 20 2020 bzcat - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Jul 20 2020 bzdiff - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4877 Sep 4 2019 bzexe - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Jul 20 2020 bzgrep - -rwxr-xr-x 3 root root 38280 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 17768 Jul 20 2020 bzip2recover - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Jul 20 2020 bzmore - -rwxr-xr-x 1 root root 38824 Sep 22 2020 cat - -rwxr-xr-x 1 root root 71624 Sep 22 2020 chgrp - -rwxr-xr-x 1 root root 67528 Sep 22 2020 chmod - -rwxr-xr-x 1 root root 75752 Sep 22 2020 chown - -rwxr-xr-x 1 root root 157960 Sep 22 2020 cp - -rwxr-xr-x 1 root root 128724 Dec 10 01:23 dash - -rwxr-xr-x 1 root root 124904 Sep 22 2020 date - -rwxr-xr-x 1 root root 92172 Sep 22 2020 dd - -rwxr-xr-x 1 root root 100752 Sep 22 2020 df - -rwxr-xr-x 1 root root 153964 Sep 22 2020 dir - -rwxr-xr-x 1 root root 83644 Feb 7 02:38 dmesg - lrwxrwxrwx 1 root root 8 Nov 6 2019 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Nov 6 2019 domainname -> hostname - -rwxr-xr-x 1 root root 34664 Sep 22 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 34664 Sep 22 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 71928 Feb 7 02:38 findmnt - -rwsr-xr-x 1 root root 30112 Feb 26 04:12 fusermount - -rwxr-xr-x 1 root root 210488 Nov 9 2020 grep - -rwxr-xr-x 2 root root 2346 Mar 2 11:30 gunzip - -rwxr-xr-x 1 root root 6376 Mar 2 11:30 gzexe - -rwxr-xr-x 1 root root 100952 Mar 2 11:30 gzip - -rwxr-xr-x 1 root root 21916 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 83980 Sep 22 2020 ln - -rwxr-xr-x 1 root root 55572 Feb 7 2020 login - -rwxr-xr-x 1 root root 153964 Sep 22 2020 ls - -rwxr-xr-x 1 root root 153124 Feb 7 02:38 lsblk - -rwxr-xr-x 1 root root 96328 Sep 22 2020 mkdir - -rwxr-xr-x 1 root root 79912 Sep 22 2020 mknod - -rwxr-xr-x 1 root root 47048 Sep 22 2020 mktemp - -rwxr-xr-x 1 root root 58920 Feb 7 02:38 more - -rwsr-xr-x 1 root root 50720 Feb 7 02:38 mount - -rwxr-xr-x 1 root root 13856 Feb 7 02:38 mountpoint - -rwxr-xr-x 1 root root 157996 Sep 22 2020 mv - lrwxrwxrwx 1 root root 8 Nov 6 2019 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Apr 18 03:38 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 38824 Sep 22 2020 pwd - lrwxrwxrwx 1 root root 4 Feb 24 08:57 rbash -> bash - -rwxr-xr-x 1 root root 46984 Sep 22 2020 readlink - -rwxr-xr-x 1 root root 75720 Sep 22 2020 rm - -rwxr-xr-x 1 root root 46984 Sep 22 2020 rmdir - -rwxr-xr-x 1 root root 22292 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 125036 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Jun 1 21:26 sh -> dash - -rwxr-xr-x 1 root root 34696 Sep 22 2020 sleep - -rwxr-xr-x 1 root root 83880 Sep 22 2020 stty - -rwsr-xr-x 1 root root 79396 Feb 7 02:38 su - -rwxr-xr-x 1 root root 34696 Sep 22 2020 sync - -rwxr-xr-x 1 root root 602584 Feb 16 21:55 tar - -rwxr-xr-x 1 root root 13860 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 108520 Sep 22 2020 touch - -rwxr-xr-x 1 root root 34664 Sep 22 2020 true - -rwxr-xr-x 1 root root 17768 Feb 26 04:12 ulockmgr_server - -rwsr-xr-x 1 root root 30236 Feb 7 02:38 umount - -rwxr-xr-x 1 root root 34664 Sep 22 2020 uname - -rwxr-xr-x 2 root root 2346 Mar 2 11:30 uncompress - -rwxr-xr-x 1 root root 153964 Sep 22 2020 vdir - -rwxr-xr-x 1 root root 63024 Feb 7 02:38 wdctl - lrwxrwxrwx 1 root root 8 Nov 6 2019 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Mar 2 11:30 zcat - -rwxr-xr-x 1 root root 1678 Mar 2 11:30 zcmp - -rwxr-xr-x 1 root root 5880 Mar 2 11:30 zdiff - -rwxr-xr-x 1 root root 29 Mar 2 11:30 zegrep - -rwxr-xr-x 1 root root 29 Mar 2 11:30 zfgrep - -rwxr-xr-x 1 root root 2081 Mar 2 11:30 zforce - -rwxr-xr-x 1 root root 7585 Mar 2 11:30 zgrep - -rwxr-xr-x 1 root root 2206 Mar 2 11:30 zless - -rwxr-xr-x 1 root root 1842 Mar 2 11:30 zmore - -rwxr-xr-x 1 root root 4553 Mar 2 11:30 znew -I: user script /srv/workspace/pbuilder/8907/tmp/hooks/D02_print_environment finished + total 5776 + -rwxr-xr-x 1 root root 1367848 Feb 25 2021 bash + -rwxr-xr-x 3 root root 38280 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 38280 Jul 21 2020 bzcat + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Jul 21 2020 bzdiff + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4877 Sep 5 2019 bzexe + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Jul 21 2020 bzgrep + -rwxr-xr-x 3 root root 38280 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 17768 Jul 21 2020 bzip2recover + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Jul 21 2020 bzmore + -rwxr-xr-x 1 root root 38824 Sep 23 2020 cat + -rwxr-xr-x 1 root root 71624 Sep 23 2020 chgrp + -rwxr-xr-x 1 root root 67528 Sep 23 2020 chmod + -rwxr-xr-x 1 root root 75752 Sep 23 2020 chown + -rwxr-xr-x 1 root root 157960 Sep 23 2020 cp + -rwxr-xr-x 1 root root 128724 Dec 11 2020 dash + -rwxr-xr-x 1 root root 124904 Sep 23 2020 date + -rwxr-xr-x 1 root root 92172 Sep 23 2020 dd + -rwxr-xr-x 1 root root 100752 Sep 23 2020 df + -rwxr-xr-x 1 root root 153964 Sep 23 2020 dir + -rwxr-xr-x 1 root root 83644 Feb 8 2021 dmesg + lrwxrwxrwx 1 root root 8 Nov 8 2019 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Nov 8 2019 domainname -> hostname + -rwxr-xr-x 1 root root 34664 Sep 23 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 34664 Sep 23 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 71928 Feb 8 2021 findmnt + -rwsr-xr-x 1 root root 30112 Feb 27 2021 fusermount + -rwxr-xr-x 1 root root 210488 Nov 10 2020 grep + -rwxr-xr-x 2 root root 2346 Mar 3 2021 gunzip + -rwxr-xr-x 1 root root 6376 Mar 3 2021 gzexe + -rwxr-xr-x 1 root root 100952 Mar 3 2021 gzip + -rwxr-xr-x 1 root root 21916 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 83980 Sep 23 2020 ln + -rwxr-xr-x 1 root root 55572 Feb 8 2020 login + -rwxr-xr-x 1 root root 153964 Sep 23 2020 ls + -rwxr-xr-x 1 root root 153124 Feb 8 2021 lsblk + -rwxr-xr-x 1 root root 96328 Sep 23 2020 mkdir + -rwxr-xr-x 1 root root 79912 Sep 23 2020 mknod + -rwxr-xr-x 1 root root 47048 Sep 23 2020 mktemp + -rwxr-xr-x 1 root root 58920 Feb 8 2021 more + -rwsr-xr-x 1 root root 50720 Feb 8 2021 mount + -rwxr-xr-x 1 root root 13856 Feb 8 2021 mountpoint + -rwxr-xr-x 1 root root 157996 Sep 23 2020 mv + lrwxrwxrwx 1 root root 8 Nov 8 2019 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 19 2021 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 38824 Sep 23 2020 pwd + lrwxrwxrwx 1 root root 4 Feb 25 2021 rbash -> bash + -rwxr-xr-x 1 root root 46984 Sep 23 2020 readlink + -rwxr-xr-x 1 root root 75720 Sep 23 2020 rm + -rwxr-xr-x 1 root root 46984 Sep 23 2020 rmdir + -rwxr-xr-x 1 root root 22292 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 125036 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Jul 8 01:53 sh -> bash + lrwxrwxrwx 1 root root 4 Jul 6 05:46 sh.distrib -> dash + -rwxr-xr-x 1 root root 34696 Sep 23 2020 sleep + -rwxr-xr-x 1 root root 83880 Sep 23 2020 stty + -rwsr-xr-x 1 root root 79396 Feb 8 2021 su + -rwxr-xr-x 1 root root 34696 Sep 23 2020 sync + -rwxr-xr-x 1 root root 602584 Feb 17 2021 tar + -rwxr-xr-x 1 root root 13860 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 108520 Sep 23 2020 touch + -rwxr-xr-x 1 root root 34664 Sep 23 2020 true + -rwxr-xr-x 1 root root 17768 Feb 27 2021 ulockmgr_server + -rwsr-xr-x 1 root root 30236 Feb 8 2021 umount + -rwxr-xr-x 1 root root 34664 Sep 23 2020 uname + -rwxr-xr-x 2 root root 2346 Mar 3 2021 uncompress + -rwxr-xr-x 1 root root 153964 Sep 23 2020 vdir + -rwxr-xr-x 1 root root 63024 Feb 8 2021 wdctl + lrwxrwxrwx 1 root root 8 Nov 8 2019 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Mar 3 2021 zcat + -rwxr-xr-x 1 root root 1678 Mar 3 2021 zcmp + -rwxr-xr-x 1 root root 5880 Mar 3 2021 zdiff + -rwxr-xr-x 1 root root 29 Mar 3 2021 zegrep + -rwxr-xr-x 1 root root 29 Mar 3 2021 zfgrep + -rwxr-xr-x 1 root root 2081 Mar 3 2021 zforce + -rwxr-xr-x 1 root root 7585 Mar 3 2021 zgrep + -rwxr-xr-x 1 root root 2206 Mar 3 2021 zless + -rwxr-xr-x 1 root root 1842 Mar 3 2021 zmore + -rwxr-xr-x 1 root root 4553 Mar 3 2021 znew +I: user script /srv/workspace/pbuilder/1527/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -299,7 +333,7 @@ Get: 79 http://deb.debian.org/debian bullseye/main i386 golang-go.uber-atomic-dev all 1.4.0-1 [6904 B] Get: 80 http://deb.debian.org/debian bullseye/main i386 golang-go.uber-multierr-dev all 1.1.0-1.1 [8028 B] Get: 81 http://deb.debian.org/debian bullseye/main i386 golang-go.uber-zap-dev all 1.15.0-2 [92.8 kB] -Fetched 103 MB in 13s (7870 kB/s) +Fetched 103 MB in 1s (76.1 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package bsdextrautils. (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 ... 19675 files and directories currently installed.) @@ -638,8 +672,44 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +The following additional packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl +The following NEW packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl usrmerge +0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. +Need to get 59.5 kB of archives. +After this operation, 157 kB of additional disk space will be used. +Get:1 http://deb.debian.org/debian bullseye/main i386 libnumber-compare-perl all 0.03-1.1 [6956 B] +Get:2 http://deb.debian.org/debian bullseye/main i386 libtext-glob-perl all 0.11-1 [8888 B] +Get:3 http://deb.debian.org/debian bullseye/main i386 libfile-find-rule-perl all 0.34-1 [30.6 kB] +Get:4 http://deb.debian.org/debian bullseye/main i386 usrmerge all 25 [13.0 kB] +debconf: delaying package configuration, since apt-utils is not installed +Fetched 59.5 kB in 0s (5247 kB/s) +Selecting previously unselected package libnumber-compare-perl. +(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 ... 43185 files and directories currently installed.) +Preparing to unpack .../libnumber-compare-perl_0.03-1.1_all.deb ... +Unpacking libnumber-compare-perl (0.03-1.1) ... +Selecting previously unselected package libtext-glob-perl. +Preparing to unpack .../libtext-glob-perl_0.11-1_all.deb ... +Unpacking libtext-glob-perl (0.11-1) ... +Selecting previously unselected package libfile-find-rule-perl. +Preparing to unpack .../libfile-find-rule-perl_0.34-1_all.deb ... +Unpacking libfile-find-rule-perl (0.34-1) ... +Selecting previously unselected package usrmerge. +Preparing to unpack .../archives/usrmerge_25_all.deb ... +Unpacking usrmerge (25) ... +Setting up libtext-glob-perl (0.11-1) ... +Setting up libnumber-compare-perl (0.03-1.1) ... +Setting up libfile-find-rule-perl (0.34-1) ... +Setting up usrmerge (25) ... +The system has been successfully converted. +Processing triggers for man-db (2.9.4-2) ... +Not building database; man-db/auto-update is not 'true'. I: Building the package -I: Running cd /build/golang-github-grpc-ecosystem-go-grpc-middleware-1.2.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b +I: Running cd /build/golang-github-grpc-ecosystem-go-grpc-middleware-1.2.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b dpkg-buildpackage: info: source package golang-github-grpc-ecosystem-go-grpc-middleware dpkg-buildpackage: info: source version 1.2.2-2 dpkg-buildpackage: info: source distribution unstable @@ -657,165 +727,165 @@ dh_autoreconf -O--buildsystem=golang dh_auto_configure -O--buildsystem=golang dh_auto_build -O--buildsystem=golang - cd obj-i686-linux-gnu && go install -trimpath -v -p 10 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/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 -unicode/utf8 + cd obj-i686-linux-gnu && go install -trimpath -v -p 18 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/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 -unicode +vendor/golang.org/x/crypto/cryptobyte/asn1 +unicode/utf8 +crypto/subtle encoding internal/race -math/bits -internal/cpu -runtime/internal/atomic +vendor/golang.org/x/crypto/internal/subtle +container/list unicode/utf16 -sync/atomic +crypto/internal/subtle internal/nettrace +runtime/internal/atomic +math/bits +sync/atomic +unicode +google.golang.org/grpc/serviceconfig +internal/cpu +golang.org/x/sys/internal/unsafeheader runtime/internal/sys -container/list -crypto/internal/subtle -crypto/subtle -vendor/golang.org/x/crypto/cryptobyte/asn1 runtime/cgo -vendor/golang.org/x/crypto/internal/subtle -math -google.golang.org/grpc/serviceconfig internal/testlog -golang.org/x/sys/internal/unsafeheader +math runtime/internal/math internal/bytealg runtime internal/reflectlite sync -google.golang.org/grpc/internal/grpcsync internal/singleflight +google.golang.org/grpc/internal/grpcsync google.golang.org/grpc/internal/buffer math/rand -sort errors +sort internal/oserror io strconv vendor/golang.org/x/net/dns/dnsmessage syscall hash -crypto/internal/randutil -strings bytes +strings +crypto/internal/randutil text/tabwriter hash/crc32 crypto/hmac -crypto -crypto/rc4 -reflect vendor/golang.org/x/crypto/hkdf -golang.org/x/text/transform vendor/golang.org/x/text/transform -google.golang.org/grpc/encoding +golang.org/x/text/transform +crypto/rc4 +crypto +reflect bufio path html +google.golang.org/grpc/encoding regexp/syntax internal/syscall/execenv internal/syscall/unix time regexp google.golang.org/grpc/backoff +context google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/keepalive -context +go.uber.org/zap/buffer github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils -internal/poll go.uber.org/atomic -go.uber.org/zap/buffer +internal/poll google.golang.org/grpc/internal/backoff go.uber.org/zap/internal/bufferpool google.golang.org/grpc/tap +os +google.golang.org/grpc/internal/envconfig +path/filepath +go.uber.org/zap/internal/exit +runtime/debug +net +io/ioutil internal/fmtsort encoding/binary -os +fmt encoding/base64 -crypto/cipher crypto/md5 -crypto/sha256 -vendor/golang.org/x/crypto/poly1305 crypto/sha1 crypto/sha512 +crypto/sha256 crypto/ed25519/internal/edwards25519 +vendor/golang.org/x/crypto/poly1305 +crypto/cipher golang.org/x/sys/unix -fmt -path/filepath -net encoding/pem -google.golang.org/grpc/internal/envconfig -crypto/aes -vendor/golang.org/x/crypto/chacha20 -io/ioutil crypto/des -go.uber.org/zap/internal/exit -runtime/debug -vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/crypto/chacha20 +crypto/aes log -vendor/golang.org/x/crypto/curve25519 encoding/hex encoding/json text/template/parse +vendor/golang.org/x/crypto/curve25519 net/url compress/flate -math/big -golang.org/x/net/internal/timeseries -vendor/golang.org/x/text/unicode/bidi -vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/net/http2/hpack +vendor/golang.org/x/text/unicode/norm +math/big mime -compress/gzip -vendor/golang.org/x/text/secure/bidirule mime/quotedprintable +golang.org/x/net/internal/timeseries +vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/text/unicode/bidi net/http/internal google.golang.org/grpc/grpclog google.golang.org/grpc/metadata google.golang.org/grpc/attributes google.golang.org/grpc/codes golang.org/x/text/unicode/bidi -text/template -google.golang.org/grpc/internal/balancerload -github.com/golang/protobuf/proto +github.com/go-logfmt/logfmt google.golang.org/grpc/connectivity golang.org/x/net/http2/hpack golang.org/x/text/unicode/norm +github.com/go-stack/stack +flag +google.golang.org/grpc/internal/balancerload github.com/grpc-ecosystem/go-grpc-middleware/util/metautils google.golang.org/grpc/internal -github.com/go-logfmt/logfmt +go.uber.org/multierr +go.uber.org/zap/internal/color +github.com/davecgh/go-spew/spew +github.com/pmezard/go-difflib/difflib +compress/gzip +runtime/trace +gopkg.in/yaml.v3 +vendor/golang.org/x/text/secure/bidirule +github.com/opentracing/opentracing-go/log +github.com/go-kit/kit/log +text/template +testing +go.uber.org/zap/zapcore +github.com/golang/protobuf/proto +github.com/gogo/protobuf/proto +golang.org/x/text/secure/bidirule vendor/golang.org/x/net/idna -github.com/go-stack/stack +github.com/go-kit/kit/log/level +github.com/sirupsen/logrus +golang.org/x/net/idna crypto/rand +crypto/dsa crypto/elliptic encoding/asn1 -crypto/dsa -golang.org/x/text/secure/bidirule crypto/ed25519 crypto/rsa -github.com/go-kit/kit/log -github.com/sirupsen/logrus -flag html/template -go.uber.org/multierr -golang.org/x/net/idna -github.com/go-kit/kit/log/level crypto/x509/pkix vendor/golang.org/x/crypto/cryptobyte -go.uber.org/zap/internal/color -go.uber.org/zap/zapcore -github.com/davecgh/go-spew/spew -github.com/pmezard/go-difflib/difflib -gopkg.in/yaml.v3 -runtime/trace -github.com/gogo/protobuf/proto -testing -github.com/opentracing/opentracing-go/log crypto/ecdsa -google.golang.org/grpc/encoding/proto -github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/timestamp github.com/golang/protobuf/ptypes/struct +google.golang.org/grpc/encoding/proto +github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/duration google.golang.org/genproto/googleapis/rpc/status github.com/golang/protobuf/ptypes @@ -823,47 +893,47 @@ github.com/golang/protobuf/jsonpb google.golang.org/grpc/status github.com/grpc-ecosystem/go-grpc-middleware/logging -google.golang.org/grpc/credentials/internal +github.com/gogo/protobuf/protoc-gen-gogo/descriptor +github.com/gogo/protobuf/gogoproto +github.com/grpc-ecosystem/go-grpc-middleware/testing/gogotestproto +google.golang.org/grpc/internal/binarylog +net/textproto +vendor/golang.org/x/net/http/httpproxy google.golang.org/grpc/internal/syscall google.golang.org/grpc/naming +google.golang.org/grpc/credentials/internal google.golang.org/grpc/stats -vendor/golang.org/x/net/http/httpproxy -net/textproto crypto/x509 -google.golang.org/grpc/internal/binarylog -golang.org/x/net/http/httpguts -mime/multipart vendor/golang.org/x/net/http/httpguts -github.com/gogo/protobuf/protoc-gen-gogo/descriptor +mime/multipart +golang.org/x/net/http/httpguts crypto/tls -github.com/gogo/protobuf/gogoproto -github.com/grpc-ecosystem/go-grpc-middleware/testing/gogotestproto -google.golang.org/grpc/credentials net/http/httptrace +google.golang.org/grpc/credentials net/http google.golang.org/grpc/resolver -google.golang.org/grpc/internal/channelz google.golang.org/grpc/peer -google.golang.org/grpc/internal/resolver/dns -google.golang.org/grpc/balancer +google.golang.org/grpc/internal/channelz google.golang.org/grpc/internal/resolver/passthrough +google.golang.org/grpc/balancer +google.golang.org/grpc/internal/resolver/dns google.golang.org/grpc/balancer/base google.golang.org/grpc/balancer/roundrobin -github.com/opentracing/opentracing-go golang.org/x/net/trace net/http/httptest +github.com/opentracing/opentracing-go net/http/httputil go.uber.org/zap 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 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 @@ -871,16 +941,16 @@ github.com/grpc-ecosystem/go-grpc-middleware/tags github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit 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/logging/logrus/ctxlogrus +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/kit github.com/grpc-ecosystem/go-grpc-middleware/tags/zap github.com/grpc-ecosystem/go-grpc-middleware/logging/zap github.com/grpc-ecosystem/go-grpc-middleware/tags/logrus github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus -github.com/grpc-ecosystem/go-grpc-middleware/testing dh_auto_test -O--buildsystem=golang - cd obj-i686-linux-gnu && go test -vet=off -v -p 10 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/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 + cd obj-i686-linux-gnu && go test -vet=off -v -p 18 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/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 --- PASS: TestChainUnaryServer (0.00s) === RUN TestChainStreamServer @@ -892,7 +962,7 @@ === RUN TestWrapServerStream --- PASS: TestWrapServerStream (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware 0.013s +ok github.com/grpc-ecosystem/go-grpc-middleware 0.012s === RUN TestAuthFromMD --- PASS: TestAuthFromMD (0.00s) === RUN TestAuthTestSuite @@ -905,34 +975,34 @@ === RUN TestAuthTestSuite/TestUnary_PassesAuth === RUN TestAuthTestSuite/TestUnary_PassesWithPerRpcCredentials === CONT TestAuthTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43969 ---- PASS: TestAuthTestSuite (4.12s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43377 +--- PASS: TestAuthTestSuite (0.34s) --- PASS: TestAuthTestSuite/TestStream_BadAuth (0.00s) --- PASS: TestAuthTestSuite/TestStream_NoAuth (0.00s) --- PASS: TestAuthTestSuite/TestStream_PassesAuth (0.00s) - --- PASS: TestAuthTestSuite/TestStream_PassesWithPerRpcCredentials (0.03s) + --- PASS: TestAuthTestSuite/TestStream_PassesWithPerRpcCredentials (0.02s) --- PASS: TestAuthTestSuite/TestUnary_BadAuth (0.00s) --- PASS: TestAuthTestSuite/TestUnary_NoAuth (0.00s) --- PASS: TestAuthTestSuite/TestUnary_PassesAuth (0.00s) - --- PASS: TestAuthTestSuite/TestUnary_PassesWithPerRpcCredentials (0.05s) + --- PASS: TestAuthTestSuite/TestUnary_PassesWithPerRpcCredentials (0.02s) === RUN TestAuthOverrideTestSuite === RUN TestAuthOverrideTestSuite/TestStream_PassesAuth === RUN TestAuthOverrideTestSuite/TestUnary_PassesAuth === CONT TestAuthOverrideTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43275 ---- PASS: TestAuthOverrideTestSuite (3.89s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:36859 +--- PASS: TestAuthOverrideTestSuite (0.54s) --- PASS: TestAuthOverrideTestSuite/TestStream_PassesAuth (0.00s) --- PASS: TestAuthOverrideTestSuite/TestUnary_PassesAuth (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/auth 8.023s +ok github.com/grpc-ecosystem/go-grpc-middleware/auth 0.886s ? github.com/grpc-ecosystem/go-grpc-middleware/logging [no test files] === RUN TestKitClientSuite === RUN TestKitClientSuite/TestPing === RUN TestKitClientSuite/TestPingError_WithCustomLevels === RUN TestKitClientSuite/TestPingList === CONT TestKitClientSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34479 ---- PASS: TestKitClientSuite (2.76s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33705 +--- PASS: TestKitClientSuite (0.67s) --- PASS: TestKitClientSuite/TestPing (0.00s) --- PASS: TestKitClientSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestKitClientSuite/TestPingList (0.00s) @@ -940,37 +1010,37 @@ === RUN TestKitClientOverrideSuite/TestPingList_HasOverrides === RUN TestKitClientOverrideSuite/TestPing_HasOverrides === CONT TestKitClientOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43555 ---- PASS: TestKitClientOverrideSuite (1.37s) - --- PASS: TestKitClientOverrideSuite/TestPingList_HasOverrides (0.01s) - --- PASS: TestKitClientOverrideSuite/TestPing_HasOverrides (0.01s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32971 +--- PASS: TestKitClientOverrideSuite (0.85s) + --- PASS: TestKitClientOverrideSuite/TestPingList_HasOverrides (0.00s) + --- PASS: TestKitClientOverrideSuite/TestPing_HasOverrides (0.00s) === RUN TestKitPayloadSuite === RUN TestKitPayloadSuite/TestPingError_LogsOnlyRequestsOnError === RUN TestKitPayloadSuite/TestPingStream_LogsAllRequestsAndResponses === 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] === CONT TestKitPayloadSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35711 ---- PASS: TestKitPayloadSuite (1.02s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42769 +--- PASS: TestKitPayloadSuite (0.48s) --- PASS: TestKitPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.00s) - --- PASS: TestKitPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.01s) + --- PASS: TestKitPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.00s) --- PASS: TestKitPayloadSuite/TestPing_LogsBothRequestAndResponse (0.00s) === RUN TestKitLoggingSuite === RUN TestKitLoggingSuite/TestPingError_WithCustomLevels === RUN TestKitLoggingSuite/TestPingList_WithCustomTags === RUN TestKitLoggingSuite/TestPing_WithCustomTags === CONT TestKitLoggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38673 ---- PASS: TestKitLoggingSuite (0.93s) - --- PASS: TestKitLoggingSuite/TestPingError_WithCustomLevels (0.01s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38227 +--- PASS: TestKitLoggingSuite (0.49s) + --- PASS: TestKitLoggingSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestKitLoggingSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestKitLoggingSuite/TestPing_WithCustomTags (0.00s) === RUN TestKitLoggingOverrideSuite === RUN TestKitLoggingOverrideSuite/TestPingList_HasOverriddenDuration === RUN TestKitLoggingOverrideSuite/TestPing_HasOverriddenDuration === CONT TestKitLoggingOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43871 ---- PASS: TestKitLoggingOverrideSuite (2.03s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32891 +--- PASS: TestKitLoggingOverrideSuite (2.63s) --- PASS: TestKitLoggingOverrideSuite/TestPingList_HasOverriddenDuration (0.00s) --- PASS: TestKitLoggingOverrideSuite/TestPing_HasOverriddenDuration (0.00s) === RUN TestKitServerOverrideSuppressedSuite @@ -978,16 +1048,16 @@ === RUN TestKitServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider === RUN TestKitServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider === CONT TestKitServerOverrideSuppressedSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45593 ---- PASS: TestKitServerOverrideSuppressedSuite (1.60s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40481 +--- PASS: TestKitServerOverrideSuppressedSuite (0.62s) --- 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 9.726s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit 5.742s testing: warning: no tests to run PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit 0.027s [no tests to run] +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/kit/ctxkit 0.011s [no tests to run] === RUN TestDurationToTimeMillisField --- PASS: TestDurationToTimeMillisField (0.00s) === RUN TestLogrusClientSuite @@ -995,8 +1065,8 @@ === RUN TestLogrusClientSuite/TestPingError_WithCustomLevels === RUN TestLogrusClientSuite/TestPingList === CONT TestLogrusClientSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39589 ---- PASS: TestLogrusClientSuite (2.03s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44481 +--- PASS: TestLogrusClientSuite (0.92s) --- PASS: TestLogrusClientSuite/TestPing (0.00s) --- PASS: TestLogrusClientSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestLogrusClientSuite/TestPingList (0.00s) @@ -1004,23 +1074,23 @@ === RUN TestLogrusClientOverrideSuite/TestPingList_HasOverrides === RUN TestLogrusClientOverrideSuite/TestPing_HasOverrides === CONT TestLogrusClientOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42303 ---- PASS: TestLogrusClientOverrideSuite (2.13s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44423 +--- PASS: TestLogrusClientOverrideSuite (1.28s) --- PASS: TestLogrusClientOverrideSuite/TestPingList_HasOverrides (0.00s) --- PASS: TestLogrusClientOverrideSuite/TestPing_HasOverrides (0.00s) === RUN TestZapLoggingClientMessageProducerSuite === RUN TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer === CONT TestZapLoggingClientMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35215 ---- PASS: TestZapLoggingClientMessageProducerSuite (0.57s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:41219 +--- PASS: TestZapLoggingClientMessageProducerSuite (1.58s) --- PASS: TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer (0.00s) === RUN TestLogrusPayloadSuite === RUN TestLogrusPayloadSuite/TestPingError_LogsOnlyRequestsOnError === RUN TestLogrusPayloadSuite/TestPingStream_LogsAllRequestsAndResponses === RUN TestLogrusPayloadSuite/TestPing_LogsBothRequestAndResponse === CONT TestLogrusPayloadSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42459 ---- PASS: TestLogrusPayloadSuite (3.73s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35009 +--- PASS: TestLogrusPayloadSuite (0.94s) --- PASS: TestLogrusPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.00s) --- PASS: TestLogrusPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.01s) --- PASS: TestLogrusPayloadSuite/TestPing_LogsBothRequestAndResponse (0.00s) @@ -1029,40 +1099,40 @@ === RUN TestLogrusServerSuite/TestPingList_WithCustomTags === RUN TestLogrusServerSuite/TestPing_WithCustomTags === CONT TestLogrusServerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44813 ---- PASS: TestLogrusServerSuite (1.02s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39375 +--- PASS: TestLogrusServerSuite (1.26s) --- PASS: TestLogrusServerSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestLogrusServerSuite/TestPingList_WithCustomTags (0.00s) - --- PASS: TestLogrusServerSuite/TestPing_WithCustomTags (0.00s) + --- PASS: TestLogrusServerSuite/TestPing_WithCustomTags (0.01s) === RUN TestLogrusServerOverrideSuite === RUN TestLogrusServerOverrideSuite/TestPingList_HasOverriddenDuration === RUN TestLogrusServerOverrideSuite/TestPing_HasOverriddenDuration === CONT TestLogrusServerOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37095 ---- PASS: TestLogrusServerOverrideSuite (1.12s) - --- PASS: TestLogrusServerOverrideSuite/TestPingList_HasOverriddenDuration (0.00s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42283 +--- PASS: TestLogrusServerOverrideSuite (2.75s) + --- PASS: TestLogrusServerOverrideSuite/TestPingList_HasOverriddenDuration (0.01s) --- PASS: TestLogrusServerOverrideSuite/TestPing_HasOverriddenDuration (0.00s) === RUN TestLogrusServerOverrideDeciderSuite === RUN TestLogrusServerOverrideDeciderSuite/TestPingError_HasOverriddenDecider === RUN TestLogrusServerOverrideDeciderSuite/TestPingList_HasOverriddenDecider === RUN TestLogrusServerOverrideDeciderSuite/TestPing_HasOverriddenDecider === CONT TestLogrusServerOverrideDeciderSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42073 ---- PASS: TestLogrusServerOverrideDeciderSuite (2.38s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43039 +--- PASS: TestLogrusServerOverrideDeciderSuite (10.92s) --- PASS: TestLogrusServerOverrideDeciderSuite/TestPingError_HasOverriddenDecider (0.00s) - --- PASS: TestLogrusServerOverrideDeciderSuite/TestPingList_HasOverriddenDecider (0.00s) + --- PASS: TestLogrusServerOverrideDeciderSuite/TestPingList_HasOverriddenDecider (0.01s) --- PASS: TestLogrusServerOverrideDeciderSuite/TestPing_HasOverriddenDecider (0.00s) === RUN TestLogrusServerMessageProducerSuite === RUN TestLogrusServerMessageProducerSuite/TestPing_HasMessageProducer === CONT TestLogrusServerMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33425 ---- PASS: TestLogrusServerMessageProducerSuite (3.22s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34427 +--- PASS: TestLogrusServerMessageProducerSuite (9.40s) --- PASS: TestLogrusServerMessageProducerSuite/TestPing_HasMessageProducer (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus 16.200s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus 29.070s testing: warning: no tests to run PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus 0.008s [no tests to run] +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/logrus/ctxlogrus 0.012s [no tests to run] === RUN TestDurationToTimeMillisField --- PASS: TestDurationToTimeMillisField (0.00s) === RUN TestZapClientSuite @@ -1070,8 +1140,8 @@ === RUN TestZapClientSuite/TestPingError_WithCustomLevels === RUN TestZapClientSuite/TestPingList === CONT TestZapClientSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:34799 ---- PASS: TestZapClientSuite (1.93s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45397 +--- PASS: TestZapClientSuite (0.91s) --- PASS: TestZapClientSuite/TestPing (0.00s) --- PASS: TestZapClientSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestZapClientSuite/TestPingList (0.00s) @@ -1079,34 +1149,34 @@ === RUN TestZapClientOverrideSuite/TestPingList_HasOverrides === RUN TestZapClientOverrideSuite/TestPing_HasOverrides === CONT TestZapClientOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40827 ---- PASS: TestZapClientOverrideSuite (6.54s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46351 +--- PASS: TestZapClientOverrideSuite (1.25s) --- PASS: TestZapClientOverrideSuite/TestPingList_HasOverrides (0.00s) --- PASS: TestZapClientOverrideSuite/TestPing_HasOverrides (0.00s) === RUN TestZapLoggingClientMessageProducerSuite === RUN TestZapLoggingClientMessageProducerSuite/TestPing_HasOverriddenMessageProducer === CONT TestZapLoggingClientMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45339 ---- PASS: TestZapLoggingClientMessageProducerSuite (1.09s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46469 +--- PASS: TestZapLoggingClientMessageProducerSuite (1.79s) --- 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.622784610010602e+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.6571948598858578e+09] === CONT TestZapPayloadSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45369 ---- PASS: TestZapPayloadSuite (0.56s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40085 +--- PASS: TestZapPayloadSuite (0.75s) --- PASS: TestZapPayloadSuite/TestPingError_LogsOnlyRequestsOnError (0.00s) - --- PASS: TestZapPayloadSuite/TestPingStream_LogsAllRequestsAndResponses (0.01s) + --- 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 === CONT TestZapLoggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45229 ---- PASS: TestZapLoggingSuite (0.58s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33763 +--- PASS: TestZapLoggingSuite (0.78s) --- PASS: TestZapLoggingSuite/TestPingError_WithCustomLevels (0.00s) --- PASS: TestZapLoggingSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestZapLoggingSuite/TestPing_WithCustomTags (0.00s) @@ -1114,8 +1184,8 @@ === RUN TestZapLoggingOverrideSuite/TestPingList_HasOverriddenDuration === RUN TestZapLoggingOverrideSuite/TestPing_HasOverriddenDuration === CONT TestZapLoggingOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40085 ---- PASS: TestZapLoggingOverrideSuite (1.08s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37673 +--- PASS: TestZapLoggingOverrideSuite (2.51s) --- PASS: TestZapLoggingOverrideSuite/TestPingList_HasOverriddenDuration (0.00s) --- PASS: TestZapLoggingOverrideSuite/TestPing_HasOverriddenDuration (0.00s) === RUN TestZapServerOverrideSuppressedSuite @@ -1123,22 +1193,22 @@ === RUN TestZapServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider === RUN TestZapServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider === CONT TestZapServerOverrideSuppressedSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:38963 ---- PASS: TestZapServerOverrideSuppressedSuite (1.51s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:36399 +--- PASS: TestZapServerOverrideSuppressedSuite (4.75s) --- PASS: TestZapServerOverrideSuppressedSuite/TestPingError_HasOverriddenDecider (0.00s) - --- PASS: TestZapServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider (0.00s) + --- PASS: TestZapServerOverrideSuppressedSuite/TestPingList_HasOverriddenDecider (0.01s) --- PASS: TestZapServerOverrideSuppressedSuite/TestPing_HasOverriddenDecider (0.00s) === RUN TestZapLoggingServerMessageProducerSuite === RUN TestZapLoggingServerMessageProducerSuite/TestPing_HasOverriddenMessageProducer === CONT TestZapLoggingServerMessageProducerSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37303 ---- PASS: TestZapLoggingServerMessageProducerSuite (1.41s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:37275 +--- PASS: TestZapLoggingServerMessageProducerSuite (2.17s) --- PASS: TestZapLoggingServerMessageProducerSuite/TestPing_HasOverriddenMessageProducer (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap 14.700s +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap 14.927s testing: warning: no tests to run PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap 0.052s [no tests to run] +ok github.com/grpc-ecosystem/go-grpc-middleware/logging/zap/ctxzap 0.013s [no tests to run] === RUN TestUnaryServerInterceptor_RateLimitPass --- PASS: TestUnaryServerInterceptor_RateLimitPass (0.00s) === RUN TestUnaryServerInterceptor_RateLimitFail @@ -1157,8 +1227,8 @@ === RUN TestRecoverySuite/TestUnary_PanickingRequest === RUN TestRecoverySuite/TestUnary_SuccessfulRequest === CONT TestRecoverySuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44633 ---- PASS: TestRecoverySuite (1.58s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45913 +--- PASS: TestRecoverySuite (0.84s) --- PASS: TestRecoverySuite/TestStream_NilPanickingReceive (0.00s) --- PASS: TestRecoverySuite/TestStream_PanickingReceive (0.00s) --- PASS: TestRecoverySuite/TestStream_SuccessfulReceive (0.00s) @@ -1171,14 +1241,14 @@ === RUN TestRecoveryOverrideSuite/TestUnary_PanickingRequest === RUN TestRecoveryOverrideSuite/TestUnary_SuccessfulRequest === CONT TestRecoveryOverrideSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:33211 ---- PASS: TestRecoveryOverrideSuite (1.73s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:44975 +--- PASS: TestRecoveryOverrideSuite (1.05s) --- 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 3.332s +ok github.com/grpc-ecosystem/go-grpc-middleware/recovery 1.898s === RUN TestRetrySuite === RUN TestRetrySuite/TestCallOptionsDontPanicWithoutInterceptor === RUN TestRetrySuite/TestServerStream_CallFailsOnDeadlineExceeded @@ -1196,22 +1266,22 @@ === RUN TestRetrySuite/TestUnary_PerCallDeadline_Succeeds === RUN TestRetrySuite/TestUnary_SucceedsOnRetriableError === CONT TestRetrySuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35705 ---- PASS: TestRetrySuite (6.47s) - --- PASS: TestRetrySuite/TestCallOptionsDontPanicWithoutInterceptor (0.03s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42111 +--- PASS: TestRetrySuite (3.76s) + --- PASS: TestRetrySuite/TestCallOptionsDontPanicWithoutInterceptor (0.02s) --- PASS: TestRetrySuite/TestServerStream_CallFailsOnDeadlineExceeded (0.15s) --- PASS: TestRetrySuite/TestServerStream_CallFailsOnOutOfRetries (0.15s) - --- PASS: TestRetrySuite/TestServerStream_CallRetrySucceeds (0.73s) + --- PASS: TestRetrySuite/TestServerStream_CallRetrySucceeds (0.72s) --- PASS: TestRetrySuite/TestServerStream_FailsOnNonRetriableError (0.00s) - --- PASS: TestRetrySuite/TestServerStream_OverrideFromContext (0.21s) + --- PASS: TestRetrySuite/TestServerStream_OverrideFromContext (0.20s) --- PASS: TestRetrySuite/TestServerStream_PerCallDeadline_FailsOnParent (0.15s) - --- PASS: TestRetrySuite/TestServerStream_PerCallDeadline_Succeeds (0.41s) + --- PASS: TestRetrySuite/TestServerStream_PerCallDeadline_Succeeds (0.40s) --- PASS: TestRetrySuite/TestServerStream_SucceedsOnRetriableError (0.10s) --- PASS: TestRetrySuite/TestUnary_FailsOnNonRetriableContextError (0.00s) --- PASS: TestRetrySuite/TestUnary_FailsOnNonRetriableError (0.00s) --- PASS: TestRetrySuite/TestUnary_OverrideFromDialOpts (0.20s) --- PASS: TestRetrySuite/TestUnary_PerCallDeadline_FailsOnParent (0.15s) - --- PASS: TestRetrySuite/TestUnary_PerCallDeadline_Succeeds (0.23s) + --- PASS: TestRetrySuite/TestUnary_PerCallDeadline_Succeeds (0.22s) --- PASS: TestRetrySuite/TestUnary_SucceedsOnRetriableError (0.10s) === RUN TestChainedRetrySuite === RUN TestChainedRetrySuite/TestStreamWithChainedInterceptors_NoFailure @@ -1219,15 +1289,15 @@ === RUN TestChainedRetrySuite/TestUnaryWithChainedInterceptors_NoFailure === RUN TestChainedRetrySuite/TestUnaryWithChainedInterceptors_WithRetry === CONT TestChainedRetrySuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:32927 ---- PASS: TestChainedRetrySuite (1.32s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:36479 +--- PASS: TestChainedRetrySuite (1.28s) --- PASS: TestChainedRetrySuite/TestStreamWithChainedInterceptors_NoFailure (0.00s) --- PASS: TestChainedRetrySuite/TestStreamWithChainedInterceptors_WithRetry (0.05s) --- PASS: TestChainedRetrySuite/TestUnaryWithChainedInterceptors_NoFailure (0.00s) --- PASS: TestChainedRetrySuite/TestUnaryWithChainedInterceptors_WithRetry (0.06s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/retry 7.812s -2021/06/04 05:29:59 proto: duplicate proto type registered: mwitkow.testproto.PingRequest +ok github.com/grpc-ecosystem/go-grpc-middleware/retry 5.046s +2022/07/07 11:54:14 proto: duplicate proto type registered: mwitkow.testproto.PingRequest === RUN TestCodeGenRequestLogFieldExtractor_ManualIsDeclared --- PASS: TestCodeGenRequestLogFieldExtractor_ManualIsDeclared (0.00s) === RUN TestTaggedRequestFiledExtractor_PingRequest @@ -1242,8 +1312,8 @@ === RUN TestTaggingSuite/TestPing_WithDeadline === RUN TestTaggingSuite/TestPing_WithNoDeadline === CONT TestTaggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:42851 ---- PASS: TestTaggingSuite (2.53s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39917 +--- PASS: TestTaggingSuite (1.26s) --- PASS: TestTaggingSuite/TestPingList_WithCustomTags (0.00s) --- PASS: TestTaggingSuite/TestPing_WithCustomTags (0.00s) --- PASS: TestTaggingSuite/TestPing_WithDeadline (0.00s) @@ -1255,15 +1325,15 @@ === RUN TestTaggingOnInitialRequestSuite/TestPing_WithDeadline === RUN TestTaggingOnInitialRequestSuite/TestPing_WithNoDeadline === CONT TestTaggingOnInitialRequestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:45773 ---- PASS: TestTaggingOnInitialRequestSuite (1.55s) - --- PASS: TestTaggingOnInitialRequestSuite/TestPingList_WithCustomTags (0.01s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:36939 +--- PASS: TestTaggingOnInitialRequestSuite (1.22s) + --- 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 4.104s +ok github.com/grpc-ecosystem/go-grpc-middleware/tags 2.482s ? 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] @@ -1273,21 +1343,21 @@ === RUN TestTaggingSuite/TestPingEmpty_NotSampleTraces === RUN TestTaggingSuite/TestPingError_PropagatesTraces interceptors_test.go:166: span: traceId=1337, spanId=50, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:166: 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:43458 span.kind:server trace.sampled:true trace.spanid:54 trace.traceid:1337] + interceptors_test.go:166: 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:42326 span.kind:server trace.sampled:true trace.spanid:54 trace.traceid:1337] interceptors_test.go:166: 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:166: span: traceId=1337, spanId=56, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] interceptors_test.go:166: span: traceId=1337, spanId=58, parentId=56, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC span.kind:client] - interceptors_test.go:166: 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:43458 span.kind:server trace.sampled:true trace.spanid:60 trace.traceid:1337] + interceptors_test.go:166: 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:42326 span.kind:server trace.sampled:true trace.spanid:60 trace.traceid:1337] === RUN TestTaggingSuite/TestPing_ClientContextTags === RUN TestTaggingSuite/TestPing_PropagatesTraces interceptors_test.go:166: span: traceId=1337, spanId=68, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:166: span: traceId=1337, spanId=72, parentId=70, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:43458 span.kind:server trace.sampled:true trace.spanid:72 trace.traceid:1337] + interceptors_test.go:166: span: traceId=1337, spanId=72, parentId=70, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:42326 span.kind:server trace.sampled:true trace.spanid:72 trace.traceid:1337] interceptors_test.go:166: span: traceId=1337, spanId=70, parentId=68, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC span.kind:client] === RUN TestTaggingSuite/TestPing_WithUnaryRequestHandlerFunc === CONT TestTaggingSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:39007 ---- PASS: TestTaggingSuite (0.75s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:36855 +--- PASS: TestTaggingSuite (1.23s) --- PASS: TestTaggingSuite/TestPingEmpty_NotSampleTraces (0.00s) --- PASS: TestTaggingSuite/TestPingError_PropagatesTraces (0.00s) --- PASS: TestTaggingSuite/TestPingList_PropagatesTraces (0.00s) @@ -1298,21 +1368,21 @@ === RUN TestTaggingSuiteJaeger/TestPingEmpty_NotSampleTraces === RUN TestTaggingSuiteJaeger/TestPingError_PropagatesTraces interceptors_test.go:166: span: traceId=1337, spanId=86, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:166: span: traceId=1337, spanId=90, parentId=88, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true grpc.request.value:something peer.address:127.0.0.1:54192 span.kind:server trace.sampled:true trace.spanid:90 trace.traceid:1337:90::1] + interceptors_test.go:166: span: traceId=1337, spanId=90, parentId=88, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true grpc.request.value:something peer.address:127.0.0.1:59198 span.kind:server trace.sampled:true trace.spanid:90 trace.traceid:1337:90::1] interceptors_test.go:166: span: traceId=1337, spanId=88, parentId=86, sampled=true, name=/mwitkow.testproto.TestService/PingError, tags: map[component:gRPC error:true span.kind:client] === RUN TestTaggingSuiteJaeger/TestPingList_PropagatesTraces interceptors_test.go:166: span: traceId=1337, spanId=92, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] interceptors_test.go:166: span: traceId=1337, spanId=94, parentId=92, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC span.kind:client] - interceptors_test.go:166: span: traceId=1337, spanId=96, parentId=94, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:54192 span.kind:server trace.sampled:true trace.spanid:96 trace.traceid:1337:96::1] + interceptors_test.go:166: span: traceId=1337, spanId=96, parentId=94, sampled=true, name=/mwitkow.testproto.TestService/PingList, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:59198 span.kind:server trace.sampled:true trace.spanid:96 trace.traceid:1337:96::1] === RUN TestTaggingSuiteJaeger/TestPing_ClientContextTags === RUN TestTaggingSuiteJaeger/TestPing_PropagatesTraces interceptors_test.go:166: span: traceId=1337, spanId=104, parentId=999, sampled=true, name=/fake/parent/http/request, tags: map[] - interceptors_test.go:166: span: traceId=1337, spanId=108, parentId=106, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:54192 span.kind:server trace.sampled:true trace.spanid:108 trace.traceid:1337:108::1] + interceptors_test.go:166: span: traceId=1337, spanId=108, parentId=106, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC grpc.request.value:something peer.address:127.0.0.1:59198 span.kind:server trace.sampled:true trace.spanid:108 trace.traceid:1337:108::1] interceptors_test.go:166: span: traceId=1337, spanId=106, parentId=104, sampled=true, name=/mwitkow.testproto.TestService/Ping, tags: map[component:gRPC span.kind:client] === RUN TestTaggingSuiteJaeger/TestPing_WithUnaryRequestHandlerFunc === CONT TestTaggingSuiteJaeger - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:41269 ---- PASS: TestTaggingSuiteJaeger (2.22s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:35991 +--- PASS: TestTaggingSuiteJaeger (1.38s) --- PASS: TestTaggingSuiteJaeger/TestPingEmpty_NotSampleTraces (0.00s) --- PASS: TestTaggingSuiteJaeger/TestPingError_PropagatesTraces (0.00s) --- PASS: TestTaggingSuiteJaeger/TestPingList_PropagatesTraces (0.00s) @@ -1320,11 +1390,11 @@ --- PASS: TestTaggingSuiteJaeger/TestPing_PropagatesTraces (0.00s) --- PASS: TestTaggingSuiteJaeger/TestPing_WithUnaryRequestHandlerFunc (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing 2.983s +ok github.com/grpc-ecosystem/go-grpc-middleware/tracing/opentracing 2.610s === RUN TestJitterUp --- PASS: TestJitterUp (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils 0.014s +ok github.com/grpc-ecosystem/go-grpc-middleware/util/backoffutils 0.005s === RUN TestNiceMD_Get --- PASS: TestNiceMD_Get (0.00s) === RUN TestNiceMD_Del @@ -1340,7 +1410,7 @@ === RUN TestNiceMD_ToIncoming --- PASS: TestNiceMD_ToIncoming (0.00s) PASS -ok github.com/grpc-ecosystem/go-grpc-middleware/util/metautils 0.011s +ok github.com/grpc-ecosystem/go-grpc-middleware/util/metautils 0.004s === RUN TestValidatorTestSuite === RUN TestValidatorTestSuite/TestInvalidErrors_BidiStream === RUN TestValidatorTestSuite/TestInvalidErrors_ServerStream @@ -1348,12 +1418,12 @@ === RUN TestValidatorTestSuite/TestValidPasses_ServerStream === RUN TestValidatorTestSuite/TestValidPasses_Unary === CONT TestValidatorTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:46351 + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:40455 === RUN TestValidatorTestSuite/TestInvalidErrors_Unary#01 === RUN TestValidatorTestSuite/TestValidPasses_Unary#01 === CONT TestValidatorTestSuite - interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:41223 ---- PASS: TestValidatorTestSuite (4.48s) + interceptor_suite.go:151: stopped grpc.Server at: 127.0.0.1:43831 +--- PASS: TestValidatorTestSuite (2.05s) --- PASS: TestValidatorTestSuite/TestInvalidErrors_BidiStream (0.00s) --- PASS: TestValidatorTestSuite/TestInvalidErrors_ServerStream (0.00s) --- PASS: TestValidatorTestSuite/TestInvalidErrors_Unary (0.00s) @@ -1362,7 +1432,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 4.497s +ok github.com/grpc-ecosystem/go-grpc-middleware/validator 2.053s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1388,12 +1458,14 @@ dpkg-source --after-build . dpkg-buildpackage: info: binary-only upload (no source included) I: copying local configuration +I: user script /srv/workspace/pbuilder/1527/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/1527/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/8907 and its subdirectories -I: Current time: Thu Jun 3 17:30:24 -12 2021 -I: pbuilder-time-stamp: 1622784624 +I: removing directory /srv/workspace/pbuilder/1527 and its subdirectories +I: Current time: Fri Jul 8 01:54:59 +14 2022 +I: pbuilder-time-stamp: 1657194899