Diff of the two buildlogs: -- --- b1/build.log 2023-04-12 10:09:29.318715837 +0000 +++ b2/build.log 2023-04-12 10:11:25.842873831 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue May 14 04:29:39 -12 2024 -I: pbuilder-time-stamp: 1715704179 +I: Current time: Thu Apr 13 00:09:32 +14 2023 +I: pbuilder-time-stamp: 1681294172 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,11 +30,20 @@ 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/26239/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/9138/tmp/hooks/D01_modify_environment starting +debug: Running on codethink16-arm64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +lrwxrwxrwx 1 root root 4 Jan 6 03:20 /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/9138/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/9138/tmp/hooks/D02_print_environment starting I: set BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME='pbuilder2' BUILD_ARCH='arm64' DEBIAN_FRONTEND='noninteractive' DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=8' @@ -44,38 +53,38 @@ IFS=' ' LANG='C' - LANGUAGE='en_US:en' + LANGUAGE='nl_BE:nl' LC_ALL='C' MAIL='/var/mail/root' OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' + PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path' PBCURRENTCOMMANDLINEOPERATION='build' PBUILDER_OPERATION='build' PBUILDER_PKGDATADIR='/usr/share/pbuilder' PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' PBUILDER_SYSCONFDIR='/etc' - PPID='26239' + PPID='9138' 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.sM5BHCjL/pbuilderrc_Pic6 --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.sM5BHCjL/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.sM5BHCjL/pbuilderrc_KKjo --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.sM5BHCjL/b2 --logfile b2/build.log --extrapackages usrmerge golang-github-hashicorp-go-plugin_1.0.1-4.dsc' SUDO_GID='117' SUDO_UID='110' SUDO_USER='jenkins' TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' + TZ='/usr/share/zoneinfo/Etc/GMT-14' USER='root' USERNAME='root' _='/usr/bin/systemd-run' http_proxy='http://192.168.101.16:3128' I: uname -a - Linux codethink15-arm64 4.15.0-208-generic #220-Ubuntu SMP Mon Mar 20 14:28:12 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-208-generic #220-Ubuntu SMP Mon Mar 20 14:28:12 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 12 04:47 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/26239/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 12 00:26 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/9138/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -198,7 +207,7 @@ Get: 67 http://deb.debian.org/debian bookworm/main arm64 golang-google-genproto-dev all 0.0~git20200413.b5235f6-3 [4033 kB] Get: 68 http://deb.debian.org/debian bookworm/main arm64 protoc-gen-go-1-3 arm64 1.3.5-4+b3 [1034 kB] Get: 69 http://deb.debian.org/debian bookworm/main arm64 golang-goprotobuf-dev arm64 1.3.5-4+b3 [3432 B] -Fetched 114 MB in 3s (35.6 MB/s) +Fetched 114 MB in 2s (68.2 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19616 files and directories currently installed.) @@ -491,10 +500,15 @@ 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/9138/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/9138/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +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 @@ -546,20 +560,20 @@ src/github.com/hashicorp/go-plugin/test/grpc/gen.go src/github.com/hashicorp/go-plugin/test/grpc/test.pb.go cd obj-aarch64-linux-gnu && go install -trimpath -v -p 8 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin github.com/hashicorp/go-plugin/test/grpc -internal/goos +internal/goarch internal/unsafeheader +internal/goos +sync/atomic +runtime/internal/syscall internal/race internal/goexperiment -unicode -internal/goarch -runtime/internal/syscall internal/cpu -sync/atomic unicode/utf8 -internal/itoa +unicode internal/abi runtime/internal/math runtime/internal/sys +internal/itoa math/bits crypto/internal/subtle crypto/subtle @@ -568,146 +582,145 @@ vendor/golang.org/x/crypto/cryptobyte/asn1 container/list internal/nettrace -math vendor/golang.org/x/crypto/internal/subtle -runtime/internal/atomic -internal/bytealg encoding +math google.golang.org/grpc/serviceconfig +internal/bytealg +runtime/internal/atomic runtime -sync internal/reflectlite +sync +internal/testlog internal/singleflight +math/rand google.golang.org/grpc/internal/buffer google.golang.org/grpc/internal/grpcsync -math/rand -internal/testlog runtime/cgo errors sort +internal/oserror io -strconv path internal/safefilepath -internal/oserror +strconv crypto/internal/nistec/fiat -vendor/golang.org/x/net/dns/dnsmessage syscall +vendor/golang.org/x/net/dns/dnsmessage bytes strings hash crypto/internal/randutil hash/crc32 -text/tabwriter crypto reflect crypto/rc4 +text/tabwriter vendor/golang.org/x/text/transform -golang.org/x/text/transform bufio regexp/syntax html net/http/internal/ascii google.golang.org/grpc/encoding +golang.org/x/text/transform internal/syscall/unix internal/syscall/execenv time regexp -context io/fs -internal/poll +context google.golang.org/grpc/backoff google.golang.org/grpc/internal/grpcrand +internal/poll google.golang.org/grpc/keepalive google.golang.org/grpc/internal/backoff -embed google.golang.org/grpc/tap golang.org/x/net/context +embed encoding/binary internal/fmtsort os +encoding/base64 +crypto/cipher crypto/internal/edwards25519/field -crypto/internal/nistec crypto/md5 -encoding/base64 vendor/golang.org/x/crypto/internal/poly1305 -crypto/cipher vendor/golang.org/x/crypto/curve25519/internal/field +crypto/internal/nistec encoding/pem golang.org/x/sys/unix -crypto/internal/edwards25519 crypto/internal/boring crypto/des vendor/golang.org/x/crypto/chacha20 -fmt -crypto/aes +crypto/internal/edwards25519 crypto/sha512 +crypto/aes crypto/hmac crypto/sha1 +vendor/golang.org/x/crypto/chacha20poly1305 crypto/sha256 +vendor/golang.org/x/crypto/hkdf +fmt internal/godebug path/filepath -vendor/golang.org/x/crypto/chacha20poly1305 -internal/intern -vendor/golang.org/x/crypto/hkdf io/ioutil +internal/intern google.golang.org/grpc/internal/envconfig os/signal net/netip os/exec +net encoding/hex net/url vendor/golang.org/x/crypto/curve25519 +log encoding/json math/big -log -text/template/parse compress/flate -net +text/template/parse golang.org/x/net/internal/timeseries vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm compress/gzip +github.com/mattn/go-isatty +github.com/mattn/go-colorable vendor/golang.org/x/net/http2/hpack +github.com/fatih/color +github.com/golang/protobuf/proto text/template +github.com/hashicorp/go-hclog vendor/golang.org/x/text/secure/bidirule mime -github.com/golang/protobuf/proto -github.com/mattn/go-isatty mime/quotedprintable -github.com/mattn/go-colorable -github.com/fatih/color net/http/internal -github.com/hashicorp/go-hclog -google.golang.org/grpc/internal/grpclog -google.golang.org/grpc/attributes crypto/elliptic crypto/internal/boring/bbig encoding/asn1 crypto/rand -crypto/ed25519 -crypto/rsa crypto/dsa vendor/golang.org/x/net/idna +crypto/ed25519 +crypto/rsa +google.golang.org/grpc/internal/grpclog +google.golang.org/grpc/attributes google.golang.org/grpc/grpclog google.golang.org/grpc/metadata html/template google.golang.org/grpc/codes google.golang.org/grpc/internal/balancerload golang.org/x/text/unicode/bidi -google.golang.org/grpc/connectivity -golang.org/x/text/unicode/norm -google.golang.org/grpc/internal vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix +google.golang.org/grpc/connectivity +google.golang.org/grpc/internal +golang.org/x/text/unicode/norm golang.org/x/net/http2/hpack -crypto/ecdsa github.com/mitchellh/go-testing-interface github.com/oklog/run -golang.org/x/text/secure/bidirule -go/token encoding/gob -golang.org/x/net/idna +crypto/ecdsa +go/token +golang.org/x/text/secure/bidirule google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/duration @@ -715,25 +728,26 @@ google.golang.org/genproto/googleapis/rpc/status github.com/golang/protobuf/ptypes google.golang.org/grpc/binarylog/grpc_binarylog_v1 +golang.org/x/net/idna google.golang.org/grpc/internal/status google.golang.org/grpc/status +google.golang.org/grpc/internal/syscall vendor/golang.org/x/net/http/httpproxy net/textproto -google.golang.org/grpc/internal/syscall google.golang.org/grpc/stats github.com/hashicorp/yamux crypto/x509 vendor/golang.org/x/net/http/httpguts -mime/multipart golang.org/x/net/http/httpguts +mime/multipart crypto/tls -google.golang.org/grpc/internal/credentials net/http/httptrace -net/http +google.golang.org/grpc/internal/credentials google.golang.org/grpc/credentials +net/http +google.golang.org/grpc/peer google.golang.org/grpc/resolver google.golang.org/grpc/internal/channelz -google.golang.org/grpc/peer google.golang.org/grpc/balancer google.golang.org/grpc/internal/grpcutil google.golang.org/grpc/balancer/grpclb/state @@ -743,14 +757,14 @@ google.golang.org/grpc/balancer/base google.golang.org/grpc/internal/serviceconfig google.golang.org/grpc/balancer/roundrobin -golang.org/x/net/trace -net/http/httputil net/rpc +net/http/httputil +golang.org/x/net/trace golang.org/x/net/http2 google.golang.org/grpc/internal/transport google.golang.org/grpc -github.com/hashicorp/go-plugin/internal/plugin google.golang.org/grpc/health/grpc_health_v1 +github.com/hashicorp/go-plugin/internal/plugin github.com/hashicorp/go-plugin/test/grpc google.golang.org/grpc/health github.com/hashicorp/go-plugin @@ -760,273 +774,273 @@ dh_auto_test cd obj-aarch64-linux-gnu && go test -vet=off -v -p 8 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin === RUN TestClient_testInterfaceReattach -2024-05-14T04:32:07.007-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon] -2024-05-14T04:32:07.008-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10251 -2024-05-14T04:32:07.008-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:07.017-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1446594251 network=unix timestamp=2024-05-14T04:32:07.016-1200 -2024-05-14T04:32:07.017-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:08.024-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:08 [ERR] plugin: plugin server: accept unix /tmp/plugin1446594251: use of closed network connection -2024-05-14T04:32:08.026-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10251 -2024-05-14T04:32:09.021-1200 [DEBUG] plugin: reattached plugin process exited -2024-05-14T04:32:09.021-1200 [DEBUG] plugin: plugin exited +2023-04-13T00:11:08.102+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon] +2023-04-13T00:11:08.102+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=18900 +2023-04-13T00:11:08.102+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:08.109+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2352899160 network=unix timestamp=2023-04-13T00:11:08.109+1400 +2023-04-13T00:11:08.109+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:09.113+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:09 [ERR] plugin: plugin server: accept unix /tmp/plugin2352899160: use of closed network connection +2023-04-13T00:11:09.115+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=18900 +2023-04-13T00:11:10.110+1400 [DEBUG] plugin: reattached plugin process exited +2023-04-13T00:11:10.110+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_testInterfaceReattach (2.01s) === RUN TestClient -2024-05-14T04:32:09.021-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock] -2024-05-14T04:32:09.022-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10614 -2024-05-14T04:32:09.022-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.030-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.030-1200 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2024-05-14T04:32:09.030-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:09.031-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10614 error="signal: killed" +2023-04-13T00:11:10.110+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock] +2023-04-13T00:11:10.111+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19317 +2023-04-13T00:11:10.111+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.118+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.118+1400 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2023-04-13T00:11:10.118+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:10.119+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19317 error="signal: killed" --- PASS: TestClient (0.01s) === RUN TestClient_killStart -2024-05-14T04:32:09.032-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin3493431347/booted] -2024-05-14T04:32:09.032-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10621 -2024-05-14T04:32:09.032-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.040-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:09.041-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10621 error="signal: killed" +2023-04-13T00:11:10.120+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin2836933476/booted] +2023-04-13T00:11:10.120+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19323 +2023-04-13T00:11:10.120+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.127+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:10.128+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19323 error="signal: killed" --- PASS: TestClient_killStart (0.01s) === RUN TestClient_testCleanup -2024-05-14T04:32:09.042-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin3340061917/output] -2024-05-14T04:32:09.042-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10628 -2024-05-14T04:32:09.042-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.050-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.050-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin771571020 network=unix timestamp=2024-05-14T04:32:09.050-1200 -2024-05-14T04:32:09.052-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:09 [ERR] plugin: plugin server: accept unix /tmp/plugin771571020: use of closed network connection -2024-05-14T04:32:09.053-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10628 -2024-05-14T04:32:09.053-1200 [DEBUG] plugin: plugin exited +2023-04-13T00:11:10.128+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin1192015446/output] +2023-04-13T00:11:10.129+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19329 +2023-04-13T00:11:10.129+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.135+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2742097248 network=unix timestamp=2023-04-13T00:11:10.135+1400 +2023-04-13T00:11:10.136+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.137+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:10 [ERR] plugin: plugin server: accept unix /tmp/plugin2742097248: use of closed network connection +2023-04-13T00:11:10.138+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19329 +2023-04-13T00:11:10.138+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_testCleanup (0.01s) === RUN TestClient_testInterface -2024-05-14T04:32:09.053-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-14T04:32:09.054-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10638 -2024-05-14T04:32:09.054-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.062-1200 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin2010782118 timestamp=2024-05-14T04:32:09.062-1200 -2024-05-14T04:32:09.062-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.065-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:09 [ERR] plugin: plugin server: accept unix /tmp/plugin2010782118: use of closed network connection -2024-05-14T04:32:09.067-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10638 -2024-05-14T04:32:09.067-1200 [DEBUG] plugin: plugin exited +2023-04-13T00:11:10.138+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2023-04-13T00:11:10.139+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19337 +2023-04-13T00:11:10.139+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.146+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3562575485 network=unix timestamp=2023-04-13T00:11:10.145+1400 +2023-04-13T00:11:10.146+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.149+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:10 [ERR] plugin: plugin server: accept unix /tmp/plugin3562575485: use of closed network connection +2023-04-13T00:11:10.150+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19337 +2023-04-13T00:11:10.150+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_testInterface (0.01s) === RUN TestClient_grpc_servercrash -2024-05-14T04:32:09.067-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-14T04:32:09.068-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10648 -2024-05-14T04:32:09.068-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.077-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4185769070 network=unix timestamp=2024-05-14T04:32:09.076-1200 -2024-05-14T04:32:09.077-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.079-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10648 error="signal: killed" -2024-05-14T04:32:09.079-1200 [DEBUG] plugin: plugin exited +2023-04-13T00:11:10.150+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2023-04-13T00:11:10.151+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19345 +2023-04-13T00:11:10.151+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.158+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2063507661 network=unix timestamp=2023-04-13T00:11:10.157+1400 +2023-04-13T00:11:10.158+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.159+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19345 error="signal: killed" +2023-04-13T00:11:10.160+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_grpc_servercrash (0.01s) === RUN TestClient_grpc -2024-05-14T04:32:09.080-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-14T04:32:09.080-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10658 -2024-05-14T04:32:09.080-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.088-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1962845267 network=unix timestamp=2024-05-14T04:32:09.087-1200 -2024-05-14T04:32:09.088-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.092-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10658 -2024-05-14T04:32:09.092-1200 [DEBUG] plugin: plugin exited +2023-04-13T00:11:10.160+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2023-04-13T00:11:10.160+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19352 +2023-04-13T00:11:10.160+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.167+1400 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin4187998297 timestamp=2023-04-13T00:11:10.167+1400 +2023-04-13T00:11:10.167+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.171+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19352 +2023-04-13T00:11:10.171+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_grpc (0.01s) === RUN TestClient_grpcNotAllowed -2024-05-14T04:32:09.093-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-14T04:32:09.093-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10668 -2024-05-14T04:32:09.093-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.101-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2193791838 network=unix timestamp=2024-05-14T04:32:09.101-1200 -2024-05-14T04:32:09.101-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.101-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:09.103-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10668 error="signal: killed" +2023-04-13T00:11:10.171+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2023-04-13T00:11:10.172+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19361 +2023-04-13T00:11:10.172+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.178+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1403538532 network=unix timestamp=2023-04-13T00:11:10.178+1400 +2023-04-13T00:11:10.178+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.178+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:10.180+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19361 error="signal: killed" --- PASS: TestClient_grpcNotAllowed (0.01s) === RUN TestClient_cmdAndReattach --- PASS: TestClient_cmdAndReattach (0.00s) === RUN TestClient_reattach -2024-05-14T04:32:09.103-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-14T04:32:09.104-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10676 -2024-05-14T04:32:09.104-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:09.111-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:09.112-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3407595862 network=unix timestamp=2024-05-14T04:32:09.111-1200 -2024-05-14T04:32:09.114-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:09 [ERR] plugin: plugin server: accept unix /tmp/plugin3407595862: use of closed network connection -2024-05-14T04:32:09.116-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10676 -2024-05-14T04:32:10.112-1200 [DEBUG] plugin: reattached plugin process exited -2024-05-14T04:32:10.112-1200 [DEBUG] plugin: plugin exited -2024-05-14T04:32:10.112-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-14T04:32:10.112-1200 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:10.180+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2023-04-13T00:11:10.180+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19367 +2023-04-13T00:11:10.180+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:10.187+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2288250816 network=unix timestamp=2023-04-13T00:11:10.187+1400 +2023-04-13T00:11:10.187+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:10.190+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:10 [ERR] plugin: plugin server: accept unix /tmp/plugin2288250816: use of closed network connection +2023-04-13T00:11:10.192+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19367 +2023-04-13T00:11:11.188+1400 [DEBUG] plugin: reattached plugin process exited +2023-04-13T00:11:11.188+1400 [DEBUG] plugin: plugin exited +2023-04-13T00:11:11.188+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2023-04-13T00:11:11.188+1400 [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_reattach (1.01s) === RUN TestClient_reattachNoProtocol -2024-05-14T04:32:10.112-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-14T04:32:10.113-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10824 -2024-05-14T04:32:10.113-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:10.121-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1165586177 network=unix timestamp=2024-05-14T04:32:10.120-1200 -2024-05-14T04:32:10.121-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:10.124-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:10 [ERR] plugin: plugin server: accept unix /tmp/plugin1165586177: use of closed network connection -2024-05-14T04:32:10.126-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10824 -2024-05-14T04:32:11.122-1200 [DEBUG] plugin: reattached plugin process exited -2024-05-14T04:32:11.122-1200 [DEBUG] plugin: plugin exited -2024-05-14T04:32:11.122-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-14T04:32:11.122-1200 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:11.188+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2023-04-13T00:11:11.189+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19576 +2023-04-13T00:11:11.189+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:11.196+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1745593258 network=unix timestamp=2023-04-13T00:11:11.196+1400 +2023-04-13T00:11:11.196+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:11.199+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:11 [ERR] plugin: plugin server: accept unix /tmp/plugin1745593258: use of closed network connection +2023-04-13T00:11:11.201+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19576 +2023-04-13T00:11:12.197+1400 [DEBUG] plugin: reattached plugin process exited +2023-04-13T00:11:12.197+1400 [DEBUG] plugin: plugin exited +2023-04-13T00:11:12.197+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2023-04-13T00:11:12.197+1400 [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_reattachNoProtocol (1.01s) === RUN TestClient_reattachGRPC -2024-05-14T04:32:11.122-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-14T04:32:11.123-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10963 -2024-05-14T04:32:11.123-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:11.131-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3203953090 network=unix timestamp=2024-05-14T04:32:11.131-1200 -2024-05-14T04:32:11.131-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:11.136-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=10963 -2024-05-14T04:32:12.133-1200 [DEBUG] plugin: reattached plugin process exited -2024-05-14T04:32:12.133-1200 [DEBUG] plugin: plugin exited -2024-05-14T04:32:12.133-1200 [DEBUG] plugin: plugin exited +2023-04-13T00:11:12.198+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2023-04-13T00:11:12.198+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19777 +2023-04-13T00:11:12.198+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:12.206+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3542801625 network=unix timestamp=2023-04-13T00:11:12.205+1400 +2023-04-13T00:11:12.206+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:12.210+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19777 +2023-04-13T00:11:13.207+1400 [DEBUG] plugin: reattached plugin process exited +2023-04-13T00:11:13.207+1400 [DEBUG] plugin: plugin exited +2023-04-13T00:11:13.207+1400 [DEBUG] plugin: plugin exited --- PASS: TestClient_reattachGRPC (1.01s) === RUN TestClient_reattachNotFound --- PASS: TestClient_reattachNotFound (0.03s) === RUN TestClientStart_badVersion -2024-05-14T04:32:12.162-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version] -2024-05-14T04:32:12.162-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11102 -2024-05-14T04:32:12.162-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.170-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:12.171-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11102 error="signal: killed" +2023-04-13T00:11:13.233+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version] +2023-04-13T00:11:13.233+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19942 +2023-04-13T00:11:13.233+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.241+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.242+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19942 error="signal: killed" --- PASS: TestClientStart_badVersion (0.01s) === RUN TestClientStart_badNegotiatedVersion -2024-05-14T04:32:12.172-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] -2024-05-14T04:32:12.172-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11108 -2024-05-14T04:32:12.172-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test +2023-04-13T00:11:13.242+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] +2023-04-13T00:11:13.243+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19964 +2023-04-13T00:11:13.243+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.250+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3341450160 network=unix timestamp=2023-04-13T00:11:13.250+1400 Incompatible API version with plugin. Plugin version: 2, Client versions: [1] -2024-05-14T04:32:12.181-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:12.181-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1743942365 network=unix timestamp=2024-05-14T04:32:12.181-1200 -2024-05-14T04:32:12.183-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11108 error="signal: killed" +2023-04-13T00:11:13.250+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.252+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19964 error="signal: killed" --- PASS: TestClientStart_badNegotiatedVersion (0.01s) === RUN TestClient_Start_Timeout -2024-05-14T04:32:12.183-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout] -2024-05-14T04:32:12.184-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11116 -2024-05-14T04:32:12.184-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.234-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:12.236-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11116 error="signal: killed" +2023-04-13T00:11:13.252+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout] +2023-04-13T00:11:13.252+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19982 +2023-04-13T00:11:13.252+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.303+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.305+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=19982 error="signal: killed" --- PASS: TestClient_Start_Timeout (0.05s) === RUN TestClient_Stderr -2024-05-14T04:32:12.236-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr] -2024-05-14T04:32:12.237-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11124 -2024-05-14T04:32:12.237-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.245-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.245-1200 [DEBUG] plugin.go-plugin.test: HELLO -2024-05-14T04:32:12.245-1200 [DEBUG] plugin.go-plugin.test: WORLD -2024-05-14T04:32:12.246-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11124 -2024-05-14T04:32:12.255-1200 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2024-05-14T04:32:12.255-1200 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.305+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr] +2023-04-13T00:11:13.305+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20072 +2023-04-13T00:11:13.305+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.312+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.312+1400 [DEBUG] plugin.go-plugin.test: HELLO +2023-04-13T00:11:13.312+1400 [DEBUG] plugin.go-plugin.test: WORLD +2023-04-13T00:11:13.313+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20072 +2023-04-13T00:11:13.323+1400 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2023-04-13T00:11:13.323+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 -2024-05-14T04:32:12.295-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin] -2024-05-14T04:32:12.295-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11148 -2024-05-14T04:32:12.295-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.303-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.304-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11148 -2024-05-14T04:32:12.354-1200 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2024-05-14T04:32:12.354-1200 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.359+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin] +2023-04-13T00:11:13.359+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20174 +2023-04-13T00:11:13.359+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.366+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.367+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20174 +2023-04-13T00:11:13.416+1400 [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2023-04-13T00:11:13.416+1400 [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_Stdin (0.06s) === RUN TestClient_SecureConfig -2024-05-14T04:32:12.866-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-14T04:32:12.866-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11226 -2024-05-14T04:32:12.866-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.877-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.877-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin558757879 network=unix timestamp=2024-05-14T04:32:12.875-1200 -2024-05-14T04:32:12.879-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:12 [ERR] plugin: plugin server: accept unix /tmp/plugin558757879: use of closed network connection -2024-05-14T04:32:12.881-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11226 -2024-05-14T04:32:12.882-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_SecureConfig (0.53s) +2023-04-13T00:11:13.922+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2023-04-13T00:11:13.922+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20968 +2023-04-13T00:11:13.922+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.930+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1540966420 network=unix timestamp=2023-04-13T00:11:13.929+1400 +2023-04-13T00:11:13.930+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.931+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:13 [ERR] plugin: plugin server: accept unix /tmp/plugin1540966420: use of closed network connection +2023-04-13T00:11:13.933+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20968 +2023-04-13T00:11:13.933+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_SecureConfig (0.52s) === RUN TestClient_TLS client_test.go:820: DM-skipped --- SKIP: TestClient_TLS (0.00s) === RUN TestClient_TLS_grpc -2024-05-14T04:32:12.883-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls] -2024-05-14T04:32:12.886-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11243 -2024-05-14T04:32:12.886-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.898-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin14278606 network=unix timestamp=2024-05-14T04:32:12.898-1200 -2024-05-14T04:32:12.898-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.918-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11243 -2024-05-14T04:32:12.918-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_TLS_grpc (0.04s) +2023-04-13T00:11:13.934+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls] +2023-04-13T00:11:13.934+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20989 +2023-04-13T00:11:13.934+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.941+1400 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin1767545130 timestamp=2023-04-13T00:11:13.941+1400 +2023-04-13T00:11:13.941+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.959+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=20989 +2023-04-13T00:11:13.959+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_TLS_grpc (0.03s) === RUN TestClient_secureConfigAndReattach --- PASS: TestClient_secureConfigAndReattach (0.00s) === RUN TestClient_ping -2024-05-14T04:32:12.918-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-14T04:32:12.919-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11258 -2024-05-14T04:32:12.919-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.927-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2227511856 network=unix timestamp=2024-05-14T04:32:12.926-1200 -2024-05-14T04:32:12.927-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.933-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:12 [ERR] plugin: plugin server: accept unix /tmp/plugin2227511856: use of closed network connection -2024-05-14T04:32:12.935-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11258 -2024-05-14T04:32:12.935-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_ping (0.02s) +2023-04-13T00:11:13.959+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2023-04-13T00:11:13.960+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21023 +2023-04-13T00:11:13.960+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.966+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1481120514 network=unix timestamp=2023-04-13T00:11:13.966+1400 +2023-04-13T00:11:13.966+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.968+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:13 [ERR] plugin: plugin server: accept unix /tmp/plugin1481120514: use of closed network connection +2023-04-13T00:11:13.969+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21023 +2023-04-13T00:11:13.969+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_ping (0.01s) === RUN TestClient_wrongVersion -2024-05-14T04:32:12.935-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] -2024-05-14T04:32:12.937-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11270 -2024-05-14T04:32:12.937-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.946-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.946-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:12.946-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin591790730 network=unix timestamp=2024-05-14T04:32:12.946-1200 -2024-05-14T04:32:12.947-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11270 error="signal: killed" +2023-04-13T00:11:13.970+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] +2023-04-13T00:11:13.970+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21042 +2023-04-13T00:11:13.970+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.978+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.978+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.978+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4046290228 network=unix timestamp=2023-04-13T00:11:13.978+1400 +2023-04-13T00:11:13.979+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21042 error="signal: killed" --- PASS: TestClient_wrongVersion (0.01s) === RUN TestClient_legacyClient -2024-05-14T04:32:12.948-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] -2024-05-14T04:32:12.948-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11277 -2024-05-14T04:32:12.948-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.958-1200 [DEBUG] plugin: using plugin: version=1 -2024-05-14T04:32:12.958-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1016717256 network=unix timestamp=2024-05-14T04:32:12.958-1200 -2024-05-14T04:32:12.959-1200 [DEBUG] plugin.go-plugin.test: 2024/05/14 04:32:12 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake -2024-05-14T04:32:12.959-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-14T04:32:12.959-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:12.961-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11277 error="signal: killed" +2023-04-13T00:11:13.980+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] +2023-04-13T00:11:13.980+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21058 +2023-04-13T00:11:13.980+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.987+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2424769455 network=unix timestamp=2023-04-13T00:11:13.987+1400 +2023-04-13T00:11:13.987+1400 [DEBUG] plugin: using plugin: version=1 +2023-04-13T00:11:13.988+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2023-04-13T00:11:13.988+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:13.988+1400 [DEBUG] plugin.go-plugin.test: 2023/04/13 00:11:13 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake +2023-04-13T00:11:13.989+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21058 error="signal: killed" --- PASS: TestClient_legacyClient (0.01s) === RUN TestClient_legacyServer -2024-05-14T04:32:12.961-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client] -2024-05-14T04:32:12.962-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11286 -2024-05-14T04:32:12.962-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:12.970-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1074067978 network=unix timestamp=2024-05-14T04:32:12.970-1200 -2024-05-14T04:32:12.971-1200 [DEBUG] plugin: using plugin: version=2 -2024-05-14T04:32:14.971-1200 [WARN] plugin: plugin failed to exit gracefully -2024-05-14T04:32:14.973-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11286 error="signal: killed" +2023-04-13T00:11:13.990+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client] +2023-04-13T00:11:13.990+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21076 +2023-04-13T00:11:13.990+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:13.997+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2168062075 network=unix timestamp=2023-04-13T00:11:13.997+1400 +2023-04-13T00:11:13.997+1400 [DEBUG] plugin: using plugin: version=2 +2023-04-13T00:11:15.999+1400 [WARN] plugin: plugin failed to exit gracefully +2023-04-13T00:11:16.001+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21076 error="signal: killed" --- PASS: TestClient_legacyServer (2.01s) === RUN TestClient_versionedClient -2024-05-14T04:32:14.974-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] -2024-05-14T04:32:14.974-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11552 -2024-05-14T04:32:14.974-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:15.143-1200 [DEBUG] plugin: using plugin: version=2 -2024-05-14T04:32:15.144-1200 [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin4294754020 timestamp=2024-05-14T04:32:15.142-1200 -2024-05-14T04:32:15.146-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11552 error="signal: killed" -2024-05-14T04:32:15.147-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_versionedClient (0.17s) +2023-04-13T00:11:16.001+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] +2023-04-13T00:11:16.001+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21822 +2023-04-13T00:11:16.001+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:16.009+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1900345168 network=unix timestamp=2023-04-13T00:11:16.009+1400 +2023-04-13T00:11:16.009+1400 [DEBUG] plugin: using plugin: version=2 +2023-04-13T00:11:16.011+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21822 error="signal: killed" +2023-04-13T00:11:16.011+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_versionedClient (0.01s) === RUN TestClient_mtlsClient -2024-05-14T04:32:15.149-1200 [INFO] plugin: configuring client automatic mTLS -2024-05-14T04:32:15.203-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls] -2024-05-14T04:32:15.213-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11581 -2024-05-14T04:32:15.213-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:15.229-1200 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2024-05-14T04:32:15.225-1200 -2024-05-14T04:32:15.268-1200 [DEBUG] plugin: using plugin: version=2 -2024-05-14T04:32:15.268-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1484251143 network=unix timestamp=2024-05-14T04:32:15.268-1200 -2024-05-14T04:32:15.319-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11581 error="signal: killed" -2024-05-14T04:32:15.319-1200 [DEBUG] plugin: plugin exited ---- PASS: TestClient_mtlsClient (0.17s) +2023-04-13T00:11:16.011+1400 [INFO] plugin: configuring client automatic mTLS +2023-04-13T00:11:16.051+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls] +2023-04-13T00:11:16.051+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21830 +2023-04-13T00:11:16.052+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:16.059+1400 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2023-04-13T00:11:16.059+1400 +2023-04-13T00:11:16.099+1400 [DEBUG] plugin: using plugin: version=2 +2023-04-13T00:11:16.099+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3027694579 network=unix timestamp=2023-04-13T00:11:16.099+1400 +2023-04-13T00:11:16.126+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21830 error="signal: killed" +2023-04-13T00:11:16.127+1400 [DEBUG] plugin: plugin exited +--- PASS: TestClient_mtlsClient (0.12s) === RUN TestClient_mtlsNetRPCClient -2024-05-14T04:32:15.319-1200 [INFO] plugin: configuring client automatic mTLS -2024-05-14T04:32:15.335-1200 [DEBUG] plugin: starting plugin: path=/tmp/go-build3158119645/b001/go-plugin.test args=[/tmp/go-build3158119645/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls] -2024-05-14T04:32:15.337-1200 [DEBUG] plugin: plugin started: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11602 -2024-05-14T04:32:15.337-1200 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build3158119645/b001/go-plugin.test -2024-05-14T04:32:15.513-1200 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2024-05-14T04:32:15.510-1200 -2024-05-14T04:32:15.609-1200 [DEBUG] plugin: using plugin: version=0 -2024-05-14T04:32:15.609-1200 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3892590220 network=unix timestamp=2024-05-14T04:32:15.601-1200 -2024-05-14T04:32:15.681-1200 [DEBUG] plugin: plugin process exited: path=/tmp/go-build3158119645/b001/go-plugin.test pid=11602 error="signal: killed" -2024-05-14T04:32:15.682-1200 [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-14T04:32:15.682-1200 [WARN] plugin: plugin failed to exit gracefully ---- PASS: TestClient_mtlsNetRPCClient (0.36s) +2023-04-13T00:11:16.127+1400 [INFO] plugin: configuring client automatic mTLS +2023-04-13T00:11:16.142+1400 [DEBUG] plugin: starting plugin: path=/tmp/go-build2015551370/b001/go-plugin.test args=[/tmp/go-build2015551370/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls] +2023-04-13T00:11:16.142+1400 [DEBUG] plugin: plugin started: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21839 +2023-04-13T00:11:16.142+1400 [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2015551370/b001/go-plugin.test +2023-04-13T00:11:16.150+1400 [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2023-04-13T00:11:16.150+1400 +2023-04-13T00:11:16.190+1400 [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3972424002 network=unix timestamp=2023-04-13T00:11:16.190+1400 +2023-04-13T00:11:16.190+1400 [DEBUG] plugin: using plugin: version=0 +2023-04-13T00:11:16.219+1400 [DEBUG] plugin: plugin process exited: path=/tmp/go-build2015551370/b001/go-plugin.test pid=21839 error="signal: killed" +2023-04-13T00:11:16.219+1400 [WARN] plugin: error closing client during Kill: err="connection is shut down" +2023-04-13T00:11:16.219+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 -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: this line is more than 32 bytes -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: long -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: and this line is more than 32 by -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: tes long -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z -2024-05-14T04:32:15.682-1200 [DEBUG] plugin.test: this line is short +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: this line is more than 32 bytes +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: long +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: and this line is more than 32 by +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: tes long +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z +2023-04-13T00:11:16.220+1400 [DEBUG] plugin.test: this line is short --- PASS: TestClient_logStderr (0.00s) === RUN TestBasicError_ImplementsError --- PASS: TestBasicError_ImplementsError (0.00s) @@ -1035,11 +1049,11 @@ === 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.01s) +--- PASS: TestGRPCConn_BidirectionalPing (0.00s) === RUN TestGRPCC_Stream ---- PASS: TestGRPCC_Stream (0.01s) +--- PASS: TestGRPCC_Stream (0.00s) === RUN TestGRPCClient_Ping --- PASS: TestGRPCClient_Ping (0.00s) === RUN TestHelperProcess @@ -1056,7 +1070,7 @@ === RUN TestProtocolSelection_no_server --- PASS: TestProtocolSelection_no_server (0.00s) PASS -ok github.com/hashicorp/go-plugin 8.726s +ok github.com/hashicorp/go-plugin 8.145s ? 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 @@ -1089,12 +1103,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/9138/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/9138/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/26239 and its subdirectories -I: Current time: Tue May 14 04:32:28 -12 2024 -I: pbuilder-time-stamp: 1715704348 +I: removing directory /srv/workspace/pbuilder/9138 and its subdirectories +I: Current time: Thu Apr 13 00:11:25 +14 2023 +I: pbuilder-time-stamp: 1681294285