Diff of the two buildlogs: -- --- b1/build.log 2023-04-13 18:37:07.637724700 +0000 +++ b2/build.log 2023-04-13 18:38:17.606618757 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Thu Apr 13 06:35:18 -12 2023 -I: pbuilder-time-stamp: 1681410918 +I: Current time: Thu May 16 15:00:09 +14 2024 +I: pbuilder-time-stamp: 1715821209 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./golang-github-hashicorp-go-plugin_1.0.1.orig.tar.xz] I: copying [./golang-github-hashicorp-go-plugin_1.0.1-4.debian.tar.xz] I: Extracting source -gpgv: Signature made Sun Feb 26 06:55:39 2023 -12 +gpgv: Signature made Mon Feb 27 08:55:39 2023 +14 gpgv: using RSA key B60EBF2984453C70D74CF478FF914AF0C2B35520 gpgv: issuer "kibi@debian.org" gpgv: Can't check signature: No public key @@ -30,135 +30,144 @@ dpkg-source: info: applying test--skip-TestClient_logger.patch I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/3432090/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/409237/tmp/hooks/D01_modify_environment starting +debug: Running on ionos5-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +lrwxrwxrwx 1 root root 4 Jan 6 2023 /bin/sh -> dash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/409237/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/409237/tmp/hooks/D02_print_environment starting I: set BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME='pbuilder2' BUILD_ARCH='amd64' DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=15' + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=16' DISTRIBUTION='bookworm' HOME='/root' HOST_ARCH='amd64' IFS=' ' - INVOCATION_ID='7f2e18a939644e75a6c12ce5fae2341d' + INVOCATION_ID='8b982e824e9349cdbc66a8137f4eecee' LANG='C' - LANGUAGE='en_US:en' + LANGUAGE='et_EE:et' LC_ALL='C' MAIL='/var/mail/root' OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' + PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path' PBCURRENTCOMMANDLINEOPERATION='build' PBUILDER_OPERATION='build' PBUILDER_PKGDATADIR='/usr/share/pbuilder' PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' PBUILDER_SYSCONFDIR='/etc' - PPID='3432090' + PPID='409237' PS1='# ' PS2='> ' PS4='+ ' PWD='/' SHELL='/bin/bash' SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.JlXGgVUv/pbuilderrc_NuxQ --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.JlXGgVUv/b1 --logfile b1/build.log golang-github-hashicorp-go-plugin_1.0.1-4.dsc' + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.JlXGgVUv/pbuilderrc_nWFu --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.JlXGgVUv/b2 --logfile b2/build.log --extrapackages usrmerge golang-github-hashicorp-go-plugin_1.0.1-4.dsc' SUDO_GID='110' SUDO_UID='105' SUDO_USER='jenkins' TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' + TZ='/usr/share/zoneinfo/Etc/GMT-14' USER='root' _='/usr/bin/systemd-run' - http_proxy='http://78.137.99.97:3128' + http_proxy='http://85.184.249.68:3128' I: uname -a - Linux ionos1-amd64 5.10.0-21-amd64 #1 SMP Debian 5.10.162-1 (2023-01-21) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-0.deb11.5-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.12-1~bpo11+1 (2023-03-05) x86_64 GNU/Linux I: ls -l /bin total 5632 - -rwxr-xr-x 1 root root 1265648 Feb 12 08:05 bash - -rwxr-xr-x 3 root root 39224 Sep 18 2022 bunzip2 - -rwxr-xr-x 3 root root 39224 Sep 18 2022 bzcat - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Sep 18 2022 bzdiff - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4893 Nov 27 2021 bzexe - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Sep 18 2022 bzgrep - -rwxr-xr-x 3 root root 39224 Sep 18 2022 bzip2 - -rwxr-xr-x 1 root root 14568 Sep 18 2022 bzip2recover - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Sep 18 2022 bzmore - -rwxr-xr-x 1 root root 44016 Sep 20 2022 cat - -rwxr-xr-x 1 root root 68656 Sep 20 2022 chgrp - -rwxr-xr-x 1 root root 64496 Sep 20 2022 chmod - -rwxr-xr-x 1 root root 72752 Sep 20 2022 chown - -rwxr-xr-x 1 root root 151152 Sep 20 2022 cp - -rwxr-xr-x 1 root root 125640 Jan 5 01:20 dash - -rwxr-xr-x 1 root root 121904 Sep 20 2022 date - -rwxr-xr-x 1 root root 89240 Sep 20 2022 dd - -rwxr-xr-x 1 root root 102200 Sep 20 2022 df - -rwxr-xr-x 1 root root 151344 Sep 20 2022 dir - -rwxr-xr-x 1 root root 88656 Mar 22 22:02 dmesg - lrwxrwxrwx 1 root root 8 Dec 19 01:33 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Dec 19 01:33 domainname -> hostname - -rwxr-xr-x 1 root root 43856 Sep 20 2022 echo - -rwxr-xr-x 1 root root 41 Jan 24 02:43 egrep - -rwxr-xr-x 1 root root 35664 Sep 20 2022 false - -rwxr-xr-x 1 root root 41 Jan 24 02:43 fgrep - -rwxr-xr-x 1 root root 85600 Mar 22 22:02 findmnt - -rwsr-xr-x 1 root root 35128 Mar 22 20:35 fusermount - -rwxr-xr-x 1 root root 203152 Jan 24 02:43 grep - -rwxr-xr-x 2 root root 2346 Apr 9 2022 gunzip - -rwxr-xr-x 1 root root 6447 Apr 9 2022 gzexe - -rwxr-xr-x 1 root root 98136 Apr 9 2022 gzip - -rwxr-xr-x 1 root root 22680 Dec 19 01:33 hostname - -rwxr-xr-x 1 root root 72824 Sep 20 2022 ln - -rwxr-xr-x 1 root root 53024 Mar 23 00:40 login - -rwxr-xr-x 1 root root 151344 Sep 20 2022 ls - -rwxr-xr-x 1 root root 207168 Mar 22 22:02 lsblk - -rwxr-xr-x 1 root root 97552 Sep 20 2022 mkdir - -rwxr-xr-x 1 root root 72912 Sep 20 2022 mknod - -rwxr-xr-x 1 root root 43952 Sep 20 2022 mktemp - -rwxr-xr-x 1 root root 59712 Mar 22 22:02 more - -rwsr-xr-x 1 root root 59704 Mar 22 22:02 mount - -rwxr-xr-x 1 root root 18744 Mar 22 22:02 mountpoint - -rwxr-xr-x 1 root root 142968 Sep 20 2022 mv - lrwxrwxrwx 1 root root 8 Dec 19 01:33 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Dec 18 05:00 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 43952 Sep 20 2022 pwd - lrwxrwxrwx 1 root root 4 Feb 12 08:05 rbash -> bash - -rwxr-xr-x 1 root root 52112 Sep 20 2022 readlink - -rwxr-xr-x 1 root root 72752 Sep 20 2022 rm - -rwxr-xr-x 1 root root 56240 Sep 20 2022 rmdir - -rwxr-xr-x 1 root root 27560 Nov 2 04:31 run-parts - -rwxr-xr-x 1 root root 126424 Jan 5 07:55 sed - lrwxrwxrwx 1 root root 4 Jan 5 01:20 sh -> dash - -rwxr-xr-x 1 root root 43888 Sep 20 2022 sleep - -rwxr-xr-x 1 root root 85008 Sep 20 2022 stty - -rwsr-xr-x 1 root root 72000 Mar 22 22:02 su - -rwxr-xr-x 1 root root 39824 Sep 20 2022 sync - -rwxr-xr-x 1 root root 531928 Feb 16 2021 tar - -rwxr-xr-x 1 root root 14520 Nov 2 04:31 tempfile - -rwxr-xr-x 1 root root 109616 Sep 20 2022 touch - -rwxr-xr-x 1 root root 35664 Sep 20 2022 true - -rwxr-xr-x 1 root root 14568 Mar 22 20:35 ulockmgr_server - -rwsr-xr-x 1 root root 35128 Mar 22 22:02 umount - -rwxr-xr-x 1 root root 43888 Sep 20 2022 uname - -rwxr-xr-x 2 root root 2346 Apr 9 2022 uncompress - -rwxr-xr-x 1 root root 151344 Sep 20 2022 vdir - -rwxr-xr-x 1 root root 72024 Mar 22 22:02 wdctl - lrwxrwxrwx 1 root root 8 Dec 19 01:33 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Apr 9 2022 zcat - -rwxr-xr-x 1 root root 1678 Apr 9 2022 zcmp - -rwxr-xr-x 1 root root 6460 Apr 9 2022 zdiff - -rwxr-xr-x 1 root root 29 Apr 9 2022 zegrep - -rwxr-xr-x 1 root root 29 Apr 9 2022 zfgrep - -rwxr-xr-x 1 root root 2081 Apr 9 2022 zforce - -rwxr-xr-x 1 root root 8103 Apr 9 2022 zgrep - -rwxr-xr-x 1 root root 2206 Apr 9 2022 zless - -rwxr-xr-x 1 root root 1842 Apr 9 2022 zmore - -rwxr-xr-x 1 root root 4577 Apr 9 2022 znew -I: user script /srv/workspace/pbuilder/3432090/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1265648 Feb 13 2023 bash + -rwxr-xr-x 3 root root 39224 Sep 19 2022 bunzip2 + -rwxr-xr-x 3 root root 39224 Sep 19 2022 bzcat + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Sep 19 2022 bzdiff + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4893 Nov 28 2021 bzexe + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Sep 19 2022 bzgrep + -rwxr-xr-x 3 root root 39224 Sep 19 2022 bzip2 + -rwxr-xr-x 1 root root 14568 Sep 19 2022 bzip2recover + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Sep 19 2022 bzmore + -rwxr-xr-x 1 root root 44016 Sep 21 2022 cat + -rwxr-xr-x 1 root root 68656 Sep 21 2022 chgrp + -rwxr-xr-x 1 root root 64496 Sep 21 2022 chmod + -rwxr-xr-x 1 root root 72752 Sep 21 2022 chown + -rwxr-xr-x 1 root root 151152 Sep 21 2022 cp + -rwxr-xr-x 1 root root 125640 Jan 6 2023 dash + -rwxr-xr-x 1 root root 121904 Sep 21 2022 date + -rwxr-xr-x 1 root root 89240 Sep 21 2022 dd + -rwxr-xr-x 1 root root 102200 Sep 21 2022 df + -rwxr-xr-x 1 root root 151344 Sep 21 2022 dir + -rwxr-xr-x 1 root root 88656 Mar 24 2023 dmesg + lrwxrwxrwx 1 root root 8 Dec 20 2022 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Dec 20 2022 domainname -> hostname + -rwxr-xr-x 1 root root 43856 Sep 21 2022 echo + -rwxr-xr-x 1 root root 41 Jan 25 2023 egrep + -rwxr-xr-x 1 root root 35664 Sep 21 2022 false + -rwxr-xr-x 1 root root 41 Jan 25 2023 fgrep + -rwxr-xr-x 1 root root 85600 Mar 24 2023 findmnt + -rwsr-xr-x 1 root root 35128 Mar 23 2023 fusermount + -rwxr-xr-x 1 root root 203152 Jan 25 2023 grep + -rwxr-xr-x 2 root root 2346 Apr 10 2022 gunzip + -rwxr-xr-x 1 root root 6447 Apr 10 2022 gzexe + -rwxr-xr-x 1 root root 98136 Apr 10 2022 gzip + -rwxr-xr-x 1 root root 22680 Dec 20 2022 hostname + -rwxr-xr-x 1 root root 72824 Sep 21 2022 ln + -rwxr-xr-x 1 root root 53024 Mar 24 2023 login + -rwxr-xr-x 1 root root 151344 Sep 21 2022 ls + -rwxr-xr-x 1 root root 207168 Mar 24 2023 lsblk + -rwxr-xr-x 1 root root 97552 Sep 21 2022 mkdir + -rwxr-xr-x 1 root root 72912 Sep 21 2022 mknod + -rwxr-xr-x 1 root root 43952 Sep 21 2022 mktemp + -rwxr-xr-x 1 root root 59712 Mar 24 2023 more + -rwsr-xr-x 1 root root 59704 Mar 24 2023 mount + -rwxr-xr-x 1 root root 18744 Mar 24 2023 mountpoint + -rwxr-xr-x 1 root root 142968 Sep 21 2022 mv + lrwxrwxrwx 1 root root 8 Dec 20 2022 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Dec 19 2022 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 43952 Sep 21 2022 pwd + lrwxrwxrwx 1 root root 4 Feb 13 2023 rbash -> bash + -rwxr-xr-x 1 root root 52112 Sep 21 2022 readlink + -rwxr-xr-x 1 root root 72752 Sep 21 2022 rm + -rwxr-xr-x 1 root root 56240 Sep 21 2022 rmdir + -rwxr-xr-x 1 root root 27560 Nov 3 2022 run-parts + -rwxr-xr-x 1 root root 126424 Jan 6 2023 sed + lrwxrwxrwx 1 root root 4 Jan 6 2023 sh -> dash + -rwxr-xr-x 1 root root 43888 Sep 21 2022 sleep + -rwxr-xr-x 1 root root 85008 Sep 21 2022 stty + -rwsr-xr-x 1 root root 72000 Mar 24 2023 su + -rwxr-xr-x 1 root root 39824 Sep 21 2022 sync + -rwxr-xr-x 1 root root 531928 Feb 17 2021 tar + -rwxr-xr-x 1 root root 14520 Nov 3 2022 tempfile + -rwxr-xr-x 1 root root 109616 Sep 21 2022 touch + -rwxr-xr-x 1 root root 35664 Sep 21 2022 true + -rwxr-xr-x 1 root root 14568 Mar 23 2023 ulockmgr_server + -rwsr-xr-x 1 root root 35128 Mar 24 2023 umount + -rwxr-xr-x 1 root root 43888 Sep 21 2022 uname + -rwxr-xr-x 2 root root 2346 Apr 10 2022 uncompress + -rwxr-xr-x 1 root root 151344 Sep 21 2022 vdir + -rwxr-xr-x 1 root root 72024 Mar 24 2023 wdctl + lrwxrwxrwx 1 root root 8 Dec 20 2022 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Apr 10 2022 zcat + -rwxr-xr-x 1 root root 1678 Apr 10 2022 zcmp + -rwxr-xr-x 1 root root 6460 Apr 10 2022 zdiff + -rwxr-xr-x 1 root root 29 Apr 10 2022 zegrep + -rwxr-xr-x 1 root root 29 Apr 10 2022 zfgrep + -rwxr-xr-x 1 root root 2081 Apr 10 2022 zforce + -rwxr-xr-x 1 root root 8103 Apr 10 2022 zgrep + -rwxr-xr-x 1 root root 2206 Apr 10 2022 zless + -rwxr-xr-x 1 root root 1842 Apr 10 2022 zmore + -rwxr-xr-x 1 root root 4577 Apr 10 2022 znew +I: user script /srv/workspace/pbuilder/409237/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -281,7 +290,7 @@ Get: 67 http://deb.debian.org/debian bookworm/main amd64 golang-google-genproto-dev all 0.0~git20200413.b5235f6-3 [4033 kB] Get: 68 http://deb.debian.org/debian bookworm/main amd64 protoc-gen-go-1-3 amd64 1.3.5-4+b3 [1209 kB] Get: 69 http://deb.debian.org/debian bookworm/main amd64 golang-goprotobuf-dev amd64 1.3.5-4+b3 [3436 B] -Fetched 121 MB in 2s (58.8 MB/s) +Fetched 121 MB in 1s (85.0 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19596 files and directories currently installed.) @@ -574,10 +583,17 @@ Reading package lists... Building dependency tree... Reading state information... +usrmerge is already the newest version (35). fakeroot is already the newest version (1.31-1.2). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/golang-github-hashicorp-go-plugin-1.0.1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../golang-github-hashicorp-go-plugin_1.0.1-4_source.changes +I: user script /srv/workspace/pbuilder/409237/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +removed '/etc/unsupported-skip-usrmerge-conversion' +The system has been successfully converted. +I: user script /srv/workspace/pbuilder/409237/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/golang-github-hashicorp-go-plugin-1.0.1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../golang-github-hashicorp-go-plugin_1.0.1-4_source.changes dpkg-buildpackage: info: source package golang-github-hashicorp-go-plugin dpkg-buildpackage: info: source version 1.0.1-4 dpkg-buildpackage: info: source distribution unstable @@ -628,27 +644,27 @@ src/github.com/hashicorp/go-plugin/internal/plugin/grpc_controller.pb.go src/github.com/hashicorp/go-plugin/test/grpc/gen.go src/github.com/hashicorp/go-plugin/test/grpc/test.pb.go - cd obj-x86_64-linux-gnu && go install -trimpath -v -p 15 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin github.com/hashicorp/go-plugin/test/grpc -internal/unsafeheader + cd obj-x86_64-linux-gnu && go install -trimpath -v -p 16 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin github.com/hashicorp/go-plugin/test/grpc internal/goarch -internal/goos +internal/race unicode/utf8 -math/bits +internal/unsafeheader runtime/internal/syscall -unicode internal/goexperiment +runtime/internal/atomic +sync/atomic +internal/goos +internal/abi +unicode internal/cpu internal/itoa -crypto/subtle -internal/abi +crypto/internal/boring/sig crypto/internal/subtle -internal/race +crypto/subtle +math/bits runtime/internal/math -sync/atomic -runtime/internal/atomic -runtime/internal/sys -crypto/internal/boring/sig unicode/utf16 +runtime/internal/sys vendor/golang.org/x/crypto/cryptobyte/asn1 container/list internal/nettrace @@ -661,136 +677,136 @@ internal/reflectlite sync internal/testlog -math/rand internal/singleflight google.golang.org/grpc/internal/buffer +math/rand google.golang.org/grpc/internal/grpcsync runtime/cgo errors sort -io internal/oserror +io path internal/safefilepath -crypto/internal/nistec/fiat vendor/golang.org/x/net/dns/dnsmessage strconv +crypto/internal/nistec/fiat syscall bytes +strings hash crypto/internal/randutil text/tabwriter -strings hash/crc32 vendor/golang.org/x/text/transform golang.org/x/text/transform crypto -reflect crypto/rc4 +reflect bufio regexp/syntax -html net/http/internal/ascii google.golang.org/grpc/encoding +html internal/syscall/execenv internal/syscall/unix time regexp context -io/fs google.golang.org/grpc/backoff -internal/poll +io/fs google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/keepalive +internal/poll google.golang.org/grpc/internal/backoff google.golang.org/grpc/tap golang.org/x/net/context embed -encoding/binary -internal/fmtsort os +internal/fmtsort +encoding/binary crypto/cipher crypto/internal/edwards25519/field crypto/md5 encoding/base64 -crypto/internal/nistec vendor/golang.org/x/crypto/internal/poly1305 +crypto/internal/nistec vendor/golang.org/x/crypto/curve25519/internal/field golang.org/x/sys/unix encoding/pem crypto/internal/edwards25519 -crypto/internal/boring -crypto/des -vendor/golang.org/x/crypto/chacha20 internal/godebug path/filepath -fmt google.golang.org/grpc/internal/envconfig io/ioutil +fmt os/signal +vendor/golang.org/x/crypto/chacha20 +crypto/des +crypto/internal/boring +internal/intern +vendor/golang.org/x/sys/cpu +crypto/aes crypto/sha512 crypto/hmac -crypto/aes crypto/sha1 crypto/sha256 -internal/intern -vendor/golang.org/x/sys/cpu -vendor/golang.org/x/crypto/hkdf net/netip -os/exec +vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/chacha20poly1305 -net +os/exec encoding/hex vendor/golang.org/x/crypto/curve25519 net/url log +math/big +compress/flate text/template/parse encoding/json -compress/flate vendor/golang.org/x/net/http2/hpack -mime/quotedprintable -mime net/http/internal +mime vendor/golang.org/x/text/unicode/norm -math/big +mime/quotedprintable +net google.golang.org/grpc/internal/grpclog google.golang.org/grpc/attributes google.golang.org/grpc/metadata google.golang.org/grpc/codes -golang.org/x/net/internal/timeseries +golang.org/x/net/http2/hpack golang.org/x/text/unicode/norm +github.com/oklog/run +encoding/gob +google.golang.org/grpc/internal/balancerload +golang.org/x/net/internal/timeseries vendor/golang.org/x/text/unicode/bidi google.golang.org/grpc/grpclog -google.golang.org/grpc/internal/balancerload golang.org/x/text/unicode/bidi -golang.org/x/net/http2/hpack github.com/mitchellh/go-testing-interface -github.com/oklog/run -encoding/gob go/token compress/gzip -github.com/mattn/go-isatty google.golang.org/grpc/connectivity google.golang.org/grpc/internal -github.com/mattn/go-colorable -github.com/fatih/color vendor/golang.org/x/text/secure/bidirule -github.com/golang/protobuf/proto -text/template +github.com/mattn/go-isatty golang.org/x/text/secure/bidirule +text/template +github.com/mattn/go-colorable +github.com/golang/protobuf/proto +github.com/fatih/color +vendor/golang.org/x/net/idna +golang.org/x/net/idna github.com/hashicorp/go-hclog -crypto/elliptic crypto/internal/boring/bbig -crypto/rand +crypto/elliptic encoding/asn1 +crypto/rand crypto/dsa -golang.org/x/net/idna -vendor/golang.org/x/net/idna crypto/ed25519 crypto/rsa -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix html/template +crypto/x509/pkix +vendor/golang.org/x/crypto/cryptobyte crypto/ecdsa google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/any @@ -802,10 +818,10 @@ google.golang.org/grpc/internal/status google.golang.org/grpc/status net/textproto -crypto/x509 -vendor/golang.org/x/net/http/httpproxy google.golang.org/grpc/internal/syscall +vendor/golang.org/x/net/http/httpproxy google.golang.org/grpc/stats +crypto/x509 github.com/hashicorp/yamux vendor/golang.org/x/net/http/httpguts mime/multipart @@ -816,20 +832,20 @@ net/http google.golang.org/grpc/credentials google.golang.org/grpc/resolver -google.golang.org/grpc/internal/channelz 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/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 +net/http/httputil golang.org/x/net/trace net/rpc -net/http/httputil golang.org/x/net/http2 google.golang.org/grpc/internal/transport google.golang.org/grpc @@ -842,275 +858,275 @@ make[1]: Entering directory '/build/golang-github-hashicorp-go-plugin-1.0.1' DH_GOLANG_EXCLUDES="examples test/grpc" \ dh_auto_test - cd obj-x86_64-linux-gnu && go test -vet=off -v -p 15 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin + cd obj-x86_64-linux-gnu && go test -vet=off -v -p 16 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin === RUN TestClient_testInterfaceReattach -2023-04-13T06:36:51.515-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon] -2023-04-13T06:36:51.518-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3444916 -2023-04-13T06:36:51.518-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:51.526-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:51.526-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2593358596 network=unix timestamp=2023-04-13T06:36:51.526-1200 -2023-04-13T06:36:52.532-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:52 [ERR] plugin: plugin server: accept unix /tmp/plugin2593358596: use of closed network connection -2023-04-13T06:36:52.534-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3444916 -2023-04-13T06:36:53.532-1200 [DEBUG] plugin: reattached plugin process exited -2023-04-13T06:36:53.533-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_testInterfaceReattach (2.02s) +2024-05-16T15:01:04.259+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon] +2024-05-16T15:01:04.259+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421351 +2024-05-16T15:01:04.259+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:04.267+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:04.267+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2063590447 network=unix timestamp=2024-05-16T15:01:04.267+1400 +2024-05-16T15:01:05.274+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:05 [ERR] plugin: plugin server: accept unix /tmp/plugin2063590447: use of closed network connection +2024-05-16T15:01:05.276+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421351 +2024-05-16T15:01:06.272+1400 [DEBUG] plugin: reattached plugin process exited +2024-05-16T15:01:06.272+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_testInterfaceReattach (2.01s) === RUN TestClient -2023-04-13T06:36:53.533-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock] -2023-04-13T06:36:53.544-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445019 -2023-04-13T06:36:53.545-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.595-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.595-1200 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2023-04-13T06:36:53.595-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:53.597-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445019 error="signal: killed" ---- PASS: TestClient (0.06s) +2024-05-16T15:01:06.272+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock] +2024-05-16T15:01:06.272+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421660 +2024-05-16T15:01:06.272+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.280+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.280+1400 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2024-05-16T15:01:06.280+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:06.281+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421660 error="signal: killed" +--- PASS: TestClient (0.01s) === RUN TestClient_killStart -2023-04-13T06:36:53.597-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin2115328905/booted] -2023-04-13T06:36:53.601-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445027 -2023-04-13T06:36:53.601-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.609-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:53.617-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445027 error="signal: killed" ---- PASS: TestClient_killStart (0.02s) +2024-05-16T15:01:06.281+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin1231169710/booted] +2024-05-16T15:01:06.282+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421666 +2024-05-16T15:01:06.282+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.289+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:06.290+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421666 error="signal: killed" +--- PASS: TestClient_killStart (0.01s) === RUN TestClient_testCleanup -2023-04-13T06:36:53.617-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin299750419/output] -2023-04-13T06:36:53.620-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445032 -2023-04-13T06:36:53.620-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.629-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.630-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1698593572 network=unix timestamp=2023-04-13T06:36:53.629-1200 -2023-04-13T06:36:53.631-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:53 [ERR] plugin: plugin server: accept unix /tmp/plugin1698593572: use of closed network connection -2023-04-13T06:36:53.638-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445032 -2023-04-13T06:36:53.638-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_testCleanup (0.02s) +2024-05-16T15:01:06.290+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin1999367266/output] +2024-05-16T15:01:06.291+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421672 +2024-05-16T15:01:06.291+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.299+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3826958504 network=unix timestamp=2024-05-16T15:01:06.299+1400 +2024-05-16T15:01:06.299+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.300+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:06 [ERR] plugin: plugin server: accept unix /tmp/plugin3826958504: use of closed network connection +2024-05-16T15:01:06.301+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421672 +2024-05-16T15:01:06.301+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_testCleanup (0.01s) === RUN TestClient_testInterface -2023-04-13T06:36:53.638-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2023-04-13T06:36:53.642-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445039 -2023-04-13T06:36:53.642-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.648-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.649-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4005242081 network=unix timestamp=2023-04-13T06:36:53.647-1200 -2023-04-13T06:36:53.655-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:53 [ERR] plugin: plugin server: accept unix /tmp/plugin4005242081: use of closed network connection -2023-04-13T06:36:53.658-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445039 -2023-04-13T06:36:53.658-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_testInterface (0.02s) +2024-05-16T15:01:06.302+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2024-05-16T15:01:06.302+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421680 +2024-05-16T15:01:06.302+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.309+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.309+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2086055477 network=unix timestamp=2024-05-16T15:01:06.309+1400 +2024-05-16T15:01:06.313+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:06 [ERR] plugin: plugin server: accept unix /tmp/plugin2086055477: use of closed network connection +2024-05-16T15:01:06.314+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421680 +2024-05-16T15:01:06.314+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_testInterface (0.01s) === RUN TestClient_grpc_servercrash -2023-04-13T06:36:53.658-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2023-04-13T06:36:53.661-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445046 -2023-04-13T06:36:53.661-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.677-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.678-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3542059527 network=unix timestamp=2023-04-13T06:36:53.677-1200 -2023-04-13T06:36:53.679-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445046 error="signal: killed" -2023-04-13T06:36:53.679-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_grpc_servercrash (0.02s) +2024-05-16T15:01:06.314+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2024-05-16T15:01:06.315+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421689 +2024-05-16T15:01:06.315+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.322+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.322+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3933229492 network=unix timestamp=2024-05-16T15:01:06.321+1400 +2024-05-16T15:01:06.323+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421689 error="signal: killed" +2024-05-16T15:01:06.323+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_grpc_servercrash (0.01s) === RUN TestClient_grpc -2023-04-13T06:36:53.679-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2023-04-13T06:36:53.682-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445053 -2023-04-13T06:36:53.682-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.694-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.695-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin545984108 network=unix timestamp=2023-04-13T06:36:53.694-1200 -2023-04-13T06:36:53.709-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445053 -2023-04-13T06:36:53.710-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_grpc (0.03s) +2024-05-16T15:01:06.323+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2024-05-16T15:01:06.324+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421696 +2024-05-16T15:01:06.324+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.331+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2863904995 network=unix timestamp=2024-05-16T15:01:06.331+1400 +2024-05-16T15:01:06.332+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.334+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421696 +2024-05-16T15:01:06.335+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_grpc (0.01s) === RUN TestClient_grpcNotAllowed -2023-04-13T06:36:53.710-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2023-04-13T06:36:53.713-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445061 -2023-04-13T06:36:53.713-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.718-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.718-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:53.718-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1305785025 network=unix timestamp=2023-04-13T06:36:53.718-1200 -2023-04-13T06:36:53.719-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445061 error="signal: killed" +2024-05-16T15:01:06.335+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2024-05-16T15:01:06.335+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421704 +2024-05-16T15:01:06.335+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.343+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.343+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin414075910 network=unix timestamp=2024-05-16T15:01:06.343+1400 +2024-05-16T15:01:06.343+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:06.344+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421704 error="signal: killed" --- PASS: TestClient_grpcNotAllowed (0.01s) === RUN TestClient_cmdAndReattach --- PASS: TestClient_cmdAndReattach (0.00s) === RUN TestClient_reattach -2023-04-13T06:36:53.720-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2023-04-13T06:36:53.723-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445067 -2023-04-13T06:36:53.723-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:53.740-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:53.741-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin40260070 network=unix timestamp=2023-04-13T06:36:53.738-1200 -2023-04-13T06:36:53.768-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:53 [ERR] plugin: plugin server: accept unix /tmp/plugin40260070: use of closed network connection -2023-04-13T06:36:53.770-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445067 -2023-04-13T06:36:54.744-1200 [DEBUG] plugin: reattached plugin process exited -2023-04-13T06:36:54.744-1200 [DEBUG] plugin: plugin exited -2023-04-13T06:36:54.745-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2023-04-13T06:36:54.745-1200 [WARN] plugin: plugin failed to exit gracefully ---- PASS: TestClient_reattach (1.02s) +2024-05-16T15:01:06.344+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2024-05-16T15:01:06.344+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421710 +2024-05-16T15:01:06.345+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:06.352+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:06.353+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin470123470 network=unix timestamp=2024-05-16T15:01:06.352+1400 +2024-05-16T15:01:06.355+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:06 [ERR] plugin: plugin server: accept unix /tmp/plugin470123470: use of closed network connection +2024-05-16T15:01:06.356+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421710 +2024-05-16T15:01:07.353+1400 [DEBUG] plugin: reattached plugin process exited +2024-05-16T15:01:07.353+1400 [DEBUG] plugin: plugin exited +2024-05-16T15:01:07.353+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2024-05-16T15:01:07.353+1400 [WARN] plugin: plugin failed to exit gracefully +--- PASS: TestClient_reattach (1.01s) === RUN TestClient_reattachNoProtocol -2023-04-13T06:36:54.745-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2023-04-13T06:36:54.756-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445093 -2023-04-13T06:36:54.757-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:54.912-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:54.913-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1631452283 network=unix timestamp=2023-04-13T06:36:54.909-1200 -2023-04-13T06:36:54.918-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:54 [ERR] plugin: plugin server: accept unix /tmp/plugin1631452283: use of closed network connection -2023-04-13T06:36:54.922-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445093 -2023-04-13T06:36:55.913-1200 [DEBUG] plugin: reattached plugin process exited -2023-04-13T06:36:55.913-1200 [DEBUG] plugin: plugin exited -2023-04-13T06:36:55.913-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2023-04-13T06:36:55.913-1200 [WARN] plugin: plugin failed to exit gracefully ---- PASS: TestClient_reattachNoProtocol (1.17s) +2024-05-16T15:01:07.353+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2024-05-16T15:01:07.354+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421898 +2024-05-16T15:01:07.354+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:07.361+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:07.361+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2325823662 network=unix timestamp=2024-05-16T15:01:07.361+1400 +2024-05-16T15:01:07.364+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:07 [ERR] plugin: plugin server: accept unix /tmp/plugin2325823662: use of closed network connection +2024-05-16T15:01:07.365+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=421898 +2024-05-16T15:01:08.362+1400 [DEBUG] plugin: reattached plugin process exited +2024-05-16T15:01:08.362+1400 [DEBUG] plugin: plugin exited +2024-05-16T15:01:08.362+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2024-05-16T15:01:08.362+1400 [WARN] plugin: plugin failed to exit gracefully +--- PASS: TestClient_reattachNoProtocol (1.01s) === RUN TestClient_reattachGRPC -2023-04-13T06:36:55.913-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2023-04-13T06:36:55.914-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445141 -2023-04-13T06:36:55.914-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:55.923-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:55.924-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2342139243 network=unix timestamp=2023-04-13T06:36:55.923-1200 -2023-04-13T06:36:55.929-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445141 -2023-04-13T06:36:56.925-1200 [DEBUG] plugin: reattached plugin process exited -2023-04-13T06:36:56.925-1200 [DEBUG] plugin: plugin exited -2023-04-13T06:36:56.925-1200 [DEBUG] plugin: plugin exited +2024-05-16T15:01:08.362+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2024-05-16T15:01:08.363+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422059 +2024-05-16T15:01:08.363+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:08.371+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2585052907 network=unix timestamp=2024-05-16T15:01:08.371+1400 +2024-05-16T15:01:08.371+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:08.380+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422059 +2024-05-16T15:01:09.373+1400 [DEBUG] plugin: reattached plugin process exited +2024-05-16T15:01:09.373+1400 [DEBUG] plugin: plugin exited +2024-05-16T15:01:09.373+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_reattachGRPC (1.01s) === RUN TestClient_reattachNotFound ---- PASS: TestClient_reattachNotFound (0.02s) +--- PASS: TestClient_reattachNotFound (0.01s) === RUN TestClientStart_badVersion -2023-04-13T06:36:56.942-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version] -2023-04-13T06:36:56.943-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445190 -2023-04-13T06:36:56.943-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:56.951-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:56.952-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445190 error="signal: killed" +2024-05-16T15:01:09.385+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version] +2024-05-16T15:01:09.386+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422366 +2024-05-16T15:01:09.386+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.393+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.394+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422366 error="signal: killed" --- PASS: TestClientStart_badVersion (0.01s) === RUN TestClientStart_badNegotiatedVersion -2023-04-13T06:36:56.952-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] -2023-04-13T06:36:56.953-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445196 -2023-04-13T06:36:56.953-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:56.961-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3758066440 network=unix timestamp=2023-04-13T06:36:56.961-1200 +2024-05-16T15:01:09.394+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] +2024-05-16T15:01:09.395+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422377 +2024-05-16T15:01:09.395+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test Incompatible API version with plugin. Plugin version: 2, Client versions: [1] -2023-04-13T06:36:56.961-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:56.962-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445196 error="signal: killed" +2024-05-16T15:01:09.402+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.402+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2553382260 network=unix timestamp=2024-05-16T15:01:09.402+1400 +2024-05-16T15:01:09.403+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422377 error="signal: killed" --- PASS: TestClientStart_badNegotiatedVersion (0.01s) === RUN TestClient_Start_Timeout -2023-04-13T06:36:56.962-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout] -2023-04-13T06:36:56.963-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445202 -2023-04-13T06:36:56.963-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.014-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:57.015-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445202 error="signal: killed" +2024-05-16T15:01:09.404+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout] +2024-05-16T15:01:09.404+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422387 +2024-05-16T15:01:09.404+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.454+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.455+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422387 error="signal: killed" --- PASS: TestClient_Start_Timeout (0.05s) === RUN TestClient_Stderr -2023-04-13T06:36:57.015-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr] -2023-04-13T06:36:57.016-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445211 -2023-04-13T06:36:57.016-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.024-1200 [DEBUG] plugin.go-plugin.test: HELLO -2023-04-13T06:36:57.024-1200 [DEBUG] plugin.go-plugin.test: WORLD -2023-04-13T06:36:57.024-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.025-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445211 -2023-04-13T06:36:57.035-1200 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2023-04-13T06:36:57.035-1200 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.455+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr] +2024-05-16T15:01:09.456+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422410 +2024-05-16T15:01:09.456+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.463+1400 [DEBUG] plugin.go-plugin.test: HELLO +2024-05-16T15:01:09.463+1400 [DEBUG] plugin.go-plugin.test: WORLD +2024-05-16T15:01:09.463+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.463+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422410 +2024-05-16T15:01:09.474+1400 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2024-05-16T15:01:09.474+1400 [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_Stderr (0.02s) === RUN TestClient_StderrJSON --- PASS: TestClient_StderrJSON (0.02s) === RUN TestClient_textLogLevel --- PASS: TestClient_textLogLevel (0.02s) === RUN TestClient_Stdin -2023-04-13T06:36:57.075-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin] -2023-04-13T06:36:57.076-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445230 -2023-04-13T06:36:57.076-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.084-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.085-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445230 -2023-04-13T06:36:57.134-1200 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2023-04-13T06:36:57.134-1200 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.512+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin] +2024-05-16T15:01:09.512+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422439 +2024-05-16T15:01:09.512+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.520+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.521+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422439 +2024-05-16T15:01:09.571+1400 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2024-05-16T15:01:09.571+1400 [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_Stdin (0.06s) === RUN TestClient_SecureConfig -2023-04-13T06:36:57.405-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2023-04-13T06:36:57.405-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445238 -2023-04-13T06:36:57.405-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.422-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.422-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1734878337 network=unix timestamp=2023-04-13T06:36:57.422-1200 -2023-04-13T06:36:57.423-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:57 [ERR] plugin: plugin server: accept unix /tmp/plugin1734878337: use of closed network connection -2023-04-13T06:36:57.425-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445238 -2023-04-13T06:36:57.425-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_SecureConfig (0.29s) +2024-05-16T15:01:09.768+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2024-05-16T15:01:09.769+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422458 +2024-05-16T15:01:09.769+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.778+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.778+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin397882435 network=unix timestamp=2024-05-16T15:01:09.777+1400 +2024-05-16T15:01:09.779+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:09 [ERR] plugin: plugin server: accept unix /tmp/plugin397882435: use of closed network connection +2024-05-16T15:01:09.780+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422458 +2024-05-16T15:01:09.780+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_SecureConfig (0.21s) === RUN TestClient_TLS client_test.go:820: DM-skipped --- SKIP: TestClient_TLS (0.00s) === RUN TestClient_TLS_grpc -2023-04-13T06:36:57.426-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls] -2023-04-13T06:36:57.426-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445246 -2023-04-13T06:36:57.426-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.434-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.434-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1822161040 network=unix timestamp=2023-04-13T06:36:57.434-1200 -2023-04-13T06:36:57.448-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445246 -2023-04-13T06:36:57.448-1200 [DEBUG] plugin: plugin exited +2024-05-16T15:01:09.780+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls] +2024-05-16T15:01:09.781+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422466 +2024-05-16T15:01:09.781+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.788+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.788+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2752818909 network=unix timestamp=2024-05-16T15:01:09.788+1400 +2024-05-16T15:01:09.800+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422466 +2024-05-16T15:01:09.800+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_TLS_grpc (0.02s) === RUN TestClient_secureConfigAndReattach --- PASS: TestClient_secureConfigAndReattach (0.00s) === RUN TestClient_ping -2023-04-13T06:36:57.448-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2023-04-13T06:36:57.448-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445254 -2023-04-13T06:36:57.448-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.456-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.456-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1497719824 network=unix timestamp=2023-04-13T06:36:57.456-1200 -2023-04-13T06:36:57.458-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:57 [ERR] plugin: plugin server: accept unix /tmp/plugin1497719824: use of closed network connection -2023-04-13T06:36:57.459-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445254 -2023-04-13T06:36:57.459-1200 [DEBUG] plugin: plugin exited +2024-05-16T15:01:09.801+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2024-05-16T15:01:09.803+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422475 +2024-05-16T15:01:09.803+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.809+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.809+1400 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin1830787443 timestamp=2024-05-16T15:01:09.809+1400 +2024-05-16T15:01:09.810+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:09 [ERR] plugin: plugin server: accept unix /tmp/plugin1830787443: use of closed network connection +2024-05-16T15:01:09.811+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422475 +2024-05-16T15:01:09.811+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_ping (0.01s) === RUN TestClient_wrongVersion -2023-04-13T06:36:57.459-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] -2023-04-13T06:36:57.459-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445262 -2023-04-13T06:36:57.459-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.468-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.468-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:57.468-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1282646326 network=unix timestamp=2023-04-13T06:36:57.468-1200 -2023-04-13T06:36:57.469-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445262 error="signal: killed" +2024-05-16T15:01:09.811+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] +2024-05-16T15:01:09.812+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422483 +2024-05-16T15:01:09.812+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.820+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.820+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin29365487 network=unix timestamp=2024-05-16T15:01:09.820+1400 +2024-05-16T15:01:09.820+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.822+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422483 error="signal: killed" --- PASS: TestClient_wrongVersion (0.01s) === RUN TestClient_legacyClient -2023-04-13T06:36:57.485-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] -2023-04-13T06:36:57.485-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445270 -2023-04-13T06:36:57.485-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.493-1200 [DEBUG] plugin: using plugin: version=1 -2023-04-13T06:36:57.493-1200 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin2216954363 timestamp=2023-04-13T06:36:57.493-1200 -2023-04-13T06:36:57.493-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2023-04-13T06:36:57.493-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:57.494-1200 [DEBUG] plugin.go-plugin.test: 2023/04/13 06:36:57 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake -2023-04-13T06:36:57.494-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445270 error="signal: killed" ---- PASS: TestClient_legacyClient (0.02s) +2024-05-16T15:01:09.822+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] +2024-05-16T15:01:09.823+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422490 +2024-05-16T15:01:09.823+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.831+1400 [DEBUG] plugin: using plugin: version=1 +2024-05-16T15:01:09.831+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3221933213 network=unix timestamp=2024-05-16T15:01:09.831+1400 +2024-05-16T15:01:09.832+1400 [DEBUG] plugin.go-plugin.test: 2024/05/16 15:01:09 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake +2024-05-16T15:01:09.832+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2024-05-16T15:01:09.832+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:09.833+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422490 error="signal: killed" +--- PASS: TestClient_legacyClient (0.01s) === RUN TestClient_legacyServer -2023-04-13T06:36:57.495-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client] -2023-04-13T06:36:57.495-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445277 -2023-04-13T06:36:57.495-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:57.504-1200 [DEBUG] plugin: using plugin: version=2 -2023-04-13T06:36:57.504-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin402302405 network=unix timestamp=2023-04-13T06:36:57.504-1200 -2023-04-13T06:36:59.505-1200 [WARN] plugin: plugin failed to exit gracefully -2023-04-13T06:36:59.506-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445277 error="signal: killed" +2024-05-16T15:01:09.833+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client] +2024-05-16T15:01:09.834+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422498 +2024-05-16T15:01:09.834+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:09.842+1400 [DEBUG] plugin: using plugin: version=2 +2024-05-16T15:01:09.842+1400 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin2910637512 timestamp=2024-05-16T15:01:09.841+1400 +2024-05-16T15:01:11.843+1400 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:11.844+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422498 error="signal: killed" --- PASS: TestClient_legacyServer (2.01s) === RUN TestClient_versionedClient -2023-04-13T06:36:59.507-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] -2023-04-13T06:36:59.507-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445335 -2023-04-13T06:36:59.507-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:59.518-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3052392196 network=unix timestamp=2023-04-13T06:36:59.518-1200 -2023-04-13T06:36:59.518-1200 [DEBUG] plugin: using plugin: version=2 -2023-04-13T06:36:59.520-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445335 error="signal: killed" -2023-04-13T06:36:59.520-1200 [DEBUG] plugin: plugin exited +2024-05-16T15:01:11.844+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] +2024-05-16T15:01:11.845+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422959 +2024-05-16T15:01:11.845+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:11.853+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2372630103 network=unix timestamp=2024-05-16T15:01:11.852+1400 +2024-05-16T15:01:11.853+1400 [DEBUG] plugin: using plugin: version=2 +2024-05-16T15:01:11.854+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422959 error="signal: killed" +2024-05-16T15:01:11.854+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_versionedClient (0.01s) === RUN TestClient_mtlsClient -2023-04-13T06:36:59.520-1200 [INFO] plugin: configuring client automatic mTLS -2023-04-13T06:36:59.557-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls] -2023-04-13T06:36:59.557-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445345 -2023-04-13T06:36:59.557-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:59.564-1200 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2023-04-13T06:36:59.564-1200 -2023-04-13T06:36:59.603-1200 [DEBUG] plugin: using plugin: version=2 -2023-04-13T06:36:59.604-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1462166342 network=unix timestamp=2023-04-13T06:36:59.603-1200 -2023-04-13T06:36:59.636-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445345 error="signal: killed" -2023-04-13T06:36:59.636-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_mtlsClient (0.12s) +2024-05-16T15:01:11.854+1400 [INFO] plugin: configuring client automatic mTLS +2024-05-16T15:01:11.886+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls] +2024-05-16T15:01:11.886+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422965 +2024-05-16T15:01:11.886+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:11.894+1400 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2024-05-16T15:01:11.894+1400 +2024-05-16T15:01:11.926+1400 [DEBUG] plugin: using plugin: version=2 +2024-05-16T15:01:11.926+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1736627395 network=unix timestamp=2024-05-16T15:01:11.926+1400 +2024-05-16T15:01:11.953+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422965 error="signal: killed" +2024-05-16T15:01:11.953+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_mtlsClient (0.10s) === RUN TestClient_mtlsNetRPCClient -2023-04-13T06:36:59.636-1200 [INFO] plugin: configuring client automatic mTLS -2023-04-13T06:36:59.651-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build2317738381/b001/go-plugin.test args=[/tmp/go-build2317738381/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls] -2023-04-13T06:36:59.652-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445357 -2023-04-13T06:36:59.652-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2317738381/b001/go-plugin.test -2023-04-13T06:36:59.659-1200 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2023-04-13T06:36:59.659-1200 -2023-04-13T06:36:59.697-1200 [DEBUG] plugin: using plugin: version=0 -2023-04-13T06:36:59.697-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3359670935 network=unix timestamp=2023-04-13T06:36:59.697-1200 -2023-04-13T06:36:59.725-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2317738381/b001/go-plugin.test pid=3445357 error="signal: killed" -2023-04-13T06:36:59.725-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2023-04-13T06:36:59.725-1200 [WARN] plugin: plugin failed to exit gracefully +2024-05-16T15:01:11.953+1400 [INFO] plugin: configuring client automatic mTLS +2024-05-16T15:01:11.965+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2005261947/b001/go-plugin.test args=[/tmp/go-build2005261947/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls] +2024-05-16T15:01:11.965+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422978 +2024-05-16T15:01:11.965+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2005261947/b001/go-plugin.test +2024-05-16T15:01:11.973+1400 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2024-05-16T15:01:11.973+1400 +2024-05-16T15:01:12.017+1400 [DEBUG] plugin: using plugin: version=0 +2024-05-16T15:01:12.017+1400 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin713658564 timestamp=2024-05-16T15:01:12.017+1400 +2024-05-16T15:01:12.042+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2005261947/b001/go-plugin.test pid=422978 error="signal: killed" +2024-05-16T15:01:12.042+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2024-05-16T15:01:12.042+1400 [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_mtlsNetRPCClient (0.09s) === RUN TestClient_logger client_test.go:1215: flakky tests, #1027551 --- SKIP: TestClient_logger (0.00s) === RUN TestClient_logStderr -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: this line is more than 32 bytes -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: long -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: and this line is more than 32 by -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: tes long -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z -2023-04-13T06:36:59.725-1200 [DEBUG] plugin.test: this line is short +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: this line is more than 32 bytes +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: long +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: and this line is more than 32 by +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: tes long +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z +2024-05-16T15:01:12.042+1400 [DEBUG] plugin.test: this line is short --- PASS: TestClient_logStderr (0.00s) === RUN TestBasicError_ImplementsError --- PASS: TestBasicError_ImplementsError (0.00s) @@ -1119,7 +1135,7 @@ === RUN TestNewBasicError_nil --- PASS: TestNewBasicError_nil (0.00s) === RUN TestGRPCClient_App ---- PASS: TestGRPCClient_App (0.01s) +--- PASS: TestGRPCClient_App (0.00s) === RUN TestGRPCConn_BidirectionalPing --- PASS: TestGRPCConn_BidirectionalPing (0.00s) === RUN TestGRPCC_Stream @@ -1140,7 +1156,7 @@ === RUN TestProtocolSelection_no_server --- PASS: TestProtocolSelection_no_server (0.00s) PASS -ok github.com/hashicorp/go-plugin 8.238s +ok github.com/hashicorp/go-plugin 7.808s ? github.com/hashicorp/go-plugin/internal/plugin [no test files] make[1]: Leaving directory '/build/golang-github-hashicorp-go-plugin-1.0.1' create-stamp debian/debhelper-build-stamp @@ -1173,12 +1189,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/409237/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/409237/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/3432090 and its subdirectories -I: Current time: Thu Apr 13 06:37:07 -12 2023 -I: pbuilder-time-stamp: 1681411027 +I: removing directory /srv/workspace/pbuilder/409237 and its subdirectories +I: Current time: Thu May 16 15:01:17 +14 2024 +I: pbuilder-time-stamp: 1715821277