Diff of the two buildlogs: -- --- b1/build.log 2024-05-20 11:50:37.946811866 +0000 +++ b2/build.log 2024-05-20 11:52:01.547255630 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun May 19 23:48:09 -12 2024 -I: pbuilder-time-stamp: 1716205689 +I: Current time: Mon Jun 23 08:13:40 +14 2025 +I: pbuilder-time-stamp: 1750616020 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -30,52 +30,84 @@ 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/3287174/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/4032177/tmp/hooks/D01_modify_environment starting +debug: Running on ionos15-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 +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jun 22 18:13 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/4032177/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/4032177/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=20 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=42 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='1cf6ca58bba54c97a42708639d389167' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='3287174' - PS1='# ' - PS2='> ' + INVOCATION_ID=a067ed8fb62c4cd4a48d6b96e94a9081 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=4032177 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.AHbISwRY/pbuilderrc_ewuC --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.AHbISwRY/b1 --logfile b1/build.log golang-github-hashicorp-go-plugin_1.0.1-4.dsc' - SUDO_GID='111' - SUDO_UID='106' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://46.16.76.132:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.AHbISwRY/pbuilderrc_VB76 --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.AHbISwRY/b2 --logfile b2/build.log golang-github-hashicorp-go-plugin_1.0.1-4.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://213.165.73.152:3128 I: uname -a - Linux ionos11-amd64 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.6.13+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.6.13-1~bpo12+1 (2024-02-15) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 19 07:43 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/3287174/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 22 14:05 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/4032177/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -205,7 +237,7 @@ Get: 74 http://deb.debian.org/debian unstable/main amd64 golang-google-genproto-dev all 0.0~git20200413.b5235f6-3 [4033 kB] Get: 75 http://deb.debian.org/debian unstable/main amd64 protoc-gen-go-1-3 amd64 1.3.5-4+b6 [1287 kB] Get: 76 http://deb.debian.org/debian unstable/main amd64 golang-goprotobuf-dev amd64 1.3.5-4+b6 [3444 B] -Fetched 87.1 MB in 6s (13.7 MB/s) +Fetched 87.1 MB in 1s (88.4 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 ... 19718 files and directories currently installed.) @@ -529,7 +561,11 @@ fakeroot is already the newest version (1.34-1). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/reproducible-path/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/4032177/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/4032177/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/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 @@ -580,38 +616,38 @@ 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 20 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin github.com/hashicorp/go-plugin/test/grpc -internal/godebugs -internal/unsafeheader -internal/goos -runtime/internal/atomic + cd obj-x86_64-linux-gnu && go install -trimpath -v -p 42 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin github.com/hashicorp/go-plugin/test/grpc internal/coverage/rtcov -math/bits -internal/race +internal/unsafeheader internal/itoa -runtime/internal/syscall -unicode/utf8 +internal/godebugs +internal/goarch unicode -sync/atomic -vendor/golang.org/x/crypto/cryptobyte/asn1 unicode/utf16 -internal/goexperiment -crypto/subtle -cmp -crypto/internal/boring/sig -internal/cpu -internal/goarch crypto/internal/alias -container/list -vendor/golang.org/x/crypto/internal/alias -internal/nettrace -encoding +crypto/internal/boring/sig +internal/race +vendor/golang.org/x/crypto/cryptobyte/asn1 log/internal +unicode/utf8 google.golang.org/grpc/serviceconfig +math/bits +runtime/internal/syscall +container/list +internal/cpu +sync/atomic +internal/goos +runtime/internal/atomic +encoding +internal/nettrace +cmp +vendor/golang.org/x/crypto/internal/alias +internal/goexperiment +crypto/subtle +internal/chacha8rand runtime/internal/math -runtime/internal/sys internal/abi -internal/chacha8rand +runtime/internal/sys slices internal/bytealg math @@ -619,154 +655,154 @@ internal/reflectlite sync internal/testlog -google.golang.org/grpc/internal/grpcsync -internal/bisect internal/singleflight google.golang.org/grpc/internal/buffer +internal/bisect +google.golang.org/grpc/internal/grpcsync runtime/cgo +internal/godebug errors sort internal/oserror io -internal/safefilepath -internal/godebug -path strconv crypto/internal/nistec/fiat +internal/intern +internal/safefilepath +path vendor/golang.org/x/net/dns/dnsmessage +math/rand syscall -bytes hash -strings -math/rand -internal/intern crypto/internal/randutil text/tabwriter internal/saferio +bytes +strings hash/crc32 crypto -reflect crypto/rc4 net/netip +reflect vendor/golang.org/x/text/transform golang.org/x/text/transform bufio -regexp/syntax -html -net/http/internal/ascii google.golang.org/grpc/encoding +net/http/internal/ascii +html +regexp/syntax +internal/syscall/execenv internal/syscall/unix time -internal/syscall/execenv regexp -context -io/fs google.golang.org/grpc/backoff google.golang.org/grpc/internal/grpcrand +context google.golang.org/grpc/keepalive +io/fs internal/poll -encoding/binary -internal/fmtsort google.golang.org/grpc/internal/backoff embed google.golang.org/grpc/tap golang.org/x/net/context +internal/fmtsort +encoding/binary +os encoding/base64 -crypto/md5 crypto/cipher -crypto/internal/edwards25519/field vendor/golang.org/x/crypto/internal/poly1305 -golang.org/x/sys/unix -os +crypto/internal/edwards25519/field +crypto/md5 crypto/internal/nistec +golang.org/x/sys/unix encoding/pem crypto/internal/edwards25519 -crypto/des -vendor/golang.org/x/crypto/chacha20 crypto/internal/boring -crypto/aes -crypto/sha512 +vendor/golang.org/x/crypto/chacha20 +crypto/des crypto/hmac +crypto/sha512 crypto/sha1 +crypto/aes crypto/sha256 vendor/golang.org/x/crypto/hkdf -crypto/ecdh -path/filepath -fmt -io/ioutil google.golang.org/grpc/internal/envconfig -os/signal +io/ioutil +path/filepath vendor/golang.org/x/sys/cpu +os/signal +fmt net +crypto/ecdh vendor/golang.org/x/crypto/chacha20poly1305 os/exec encoding/hex -net/url log +net/url +mime/quotedprintable +google.golang.org/grpc/internal/grpclog +google.golang.org/grpc/attributes google.golang.org/grpc/metadata google.golang.org/grpc/codes +vendor/golang.org/x/net/http2/hpack +mime +github.com/oklog/run +text/template/parse +net/http/internal vendor/golang.org/x/text/unicode/norm +go/token compress/flate -text/template/parse -vendor/golang.org/x/net/http2/hpack math/big -mime/quotedprintable -net/http/internal -mime -google.golang.org/grpc/attributes -google.golang.org/grpc/internal/grpclog golang.org/x/net/http2/hpack +encoding/gob encoding/json golang.org/x/text/unicode/norm -github.com/oklog/run google.golang.org/grpc/internal/balancerload -go/token -encoding/gob -google.golang.org/grpc/grpclog -golang.org/x/text/unicode/bidi -vendor/golang.org/x/text/unicode/bidi github.com/mitchellh/go-testing-interface +google.golang.org/grpc/grpclog golang.org/x/net/internal/timeseries +vendor/golang.org/x/text/unicode/bidi +golang.org/x/text/unicode/bidi google.golang.org/grpc/connectivity -google.golang.org/grpc/internal compress/gzip -golang.org/x/text/secure/bidirule +google.golang.org/grpc/internal vendor/golang.org/x/text/secure/bidirule +golang.org/x/text/secure/bidirule text/template -vendor/golang.org/x/net/idna golang.org/x/net/idna +vendor/golang.org/x/net/idna github.com/golang/protobuf/proto -github.com/mattn/go-isatty -github.com/mattn/go-colorable +crypto/internal/boring/bbig crypto/elliptic +crypto/dsa crypto/internal/bigmod -crypto/internal/boring/bbig crypto/rand -crypto/dsa encoding/asn1 -github.com/fatih/color +github.com/mattn/go-isatty crypto/ed25519 -github.com/hashicorp/go-hclog +github.com/mattn/go-colorable +github.com/fatih/color crypto/rsa html/template -vendor/golang.org/x/crypto/cryptobyte +github.com/hashicorp/go-hclog crypto/x509/pkix +vendor/golang.org/x/crypto/cryptobyte crypto/ecdsa google.golang.org/grpc/encoding/proto -github.com/golang/protobuf/ptypes/duration github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/timestamp +github.com/golang/protobuf/ptypes/duration +google.golang.org/grpc/binarylog/grpc_binarylog_v1 google.golang.org/genproto/googleapis/rpc/status github.com/golang/protobuf/ptypes -google.golang.org/grpc/binarylog/grpc_binarylog_v1 google.golang.org/grpc/internal/status google.golang.org/grpc/status -net/textproto -crypto/x509 -github.com/hashicorp/yamux -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 +net/textproto +github.com/hashicorp/yamux +crypto/x509 vendor/golang.org/x/net/http/httpguts golang.org/x/net/http/httpguts mime/multipart @@ -778,10 +814,10 @@ google.golang.org/grpc/resolver google.golang.org/grpc/peer google.golang.org/grpc/internal/channelz -google.golang.org/grpc/balancer -google.golang.org/grpc/internal/grpcutil google.golang.org/grpc/balancer/grpclb/state +google.golang.org/grpc/balancer 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/internal/serviceconfig @@ -802,277 +838,275 @@ make[1]: Entering directory '/build/reproducible-path/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 20 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin + cd obj-x86_64-linux-gnu && go test -vet=off -v -p 42 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin ? github.com/hashicorp/go-plugin/internal/plugin [no test files] === RUN TestClient_testInterfaceReattach -2024-05-20T11:50:21.437Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon] -2024-05-20T11:50:21.438Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345007 -2024-05-20T11:50:21.438Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:21.446Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:21.446Z [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin2112387959 timestamp=2024-05-20T11:50:21.446Z -2024-05-20T11:50:22.454Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:22 [ERR] plugin: stream copy 'stderr' error: session shutdown -2024-05-20T11:50:22.455Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:22 [ERR] plugin: plugin server: accept unix /tmp/plugin2112387959: use of closed network connection -2024-05-20T11:50:22.456Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345007 -2024-05-20T11:50:23.450Z [DEBUG] plugin: reattached plugin process exited -2024-05-20T11:50:23.450Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:43.776Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon] +2025-06-22T18:14:43.776Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4044898 +2025-06-22T18:14:43.776Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:43.781Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2250614790 network=unix timestamp=2025-06-22T18:14:43.780Z +2025-06-22T18:14:43.781Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:44.917Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:44 [ERR] plugin: plugin server: accept unix /tmp/plugin2250614790: use of closed network connection +2025-06-22T18:14:44.961Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4044898 +2025-06-22T18:14:45.781Z [DEBUG] plugin: reattached plugin process exited +2025-06-22T18:14:45.781Z [DEBUG] plugin: plugin exited --- PASS: TestClient_testInterfaceReattach (2.01s) === RUN TestClient -2024-05-20T11:50:23.451Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock] -2024-05-20T11:50:23.451Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345045 -2024-05-20T11:50:23.451Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.457Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.457Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2024-05-20T11:50:23.457Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:23.458Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345045 error="signal: killed" +2025-06-22T18:14:45.781Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock] +2025-06-22T18:14:45.782Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045265 +2025-06-22T18:14:45.782Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.786Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.786Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2025-06-22T18:14:45.786Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:45.786Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045265 error="signal: killed" --- PASS: TestClient (0.01s) === RUN TestClient_killStart -2024-05-20T11:50:23.458Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin277043431/booted] -2024-05-20T11:50:23.459Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345051 -2024-05-20T11:50:23.459Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.466Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:23.466Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345051 error="signal: killed" +2025-06-22T18:14:45.787Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin2417477000/booted] +2025-06-22T18:14:45.787Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045271 +2025-06-22T18:14:45.787Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.792Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:45.792Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045271 error="signal: killed" --- PASS: TestClient_killStart (0.01s) === RUN TestClient_testCleanup -2024-05-20T11:50:23.467Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin300676635/output] -2024-05-20T11:50:23.467Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345057 -2024-05-20T11:50:23.467Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.474Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.474Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin583066588 network=unix timestamp=2024-05-20T11:50:23.474Z -2024-05-20T11:50:23.476Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:23 [ERR] plugin: plugin server: accept unix /tmp/plugin583066588: use of closed network connection -2024-05-20T11:50:23.477Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345057 -2024-05-20T11:50:23.477Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:45.792Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin1310703619/output] +2025-06-22T18:14:45.792Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045277 +2025-06-22T18:14:45.792Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.796Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.796Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1036861488 network=unix timestamp=2025-06-22T18:14:45.796Z +2025-06-22T18:14:45.797Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:45 [ERR] plugin: plugin server: accept unix /tmp/plugin1036861488: use of closed network connection +2025-06-22T18:14:45.798Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045277 +2025-06-22T18:14:45.798Z [DEBUG] plugin: plugin exited --- PASS: TestClient_testCleanup (0.01s) === RUN TestClient_testInterface -2024-05-20T11:50:23.478Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-20T11:50:23.478Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345067 -2024-05-20T11:50:23.478Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.486Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.486Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1989189267 network=unix timestamp=2024-05-20T11:50:23.486Z -2024-05-20T11:50:23.489Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:23 [ERR] plugin: plugin server: accept unix /tmp/plugin1989189267: use of closed network connection -2024-05-20T11:50:23.490Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345067 -2024-05-20T11:50:23.490Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:45.798Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2025-06-22T18:14:45.798Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045287 +2025-06-22T18:14:45.798Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.802Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.802Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3388128354 network=unix timestamp=2025-06-22T18:14:45.802Z +2025-06-22T18:14:45.804Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:45 [ERR] plugin: plugin server: accept unix /tmp/plugin3388128354: use of closed network connection +2025-06-22T18:14:45.804Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045287 +2025-06-22T18:14:45.804Z [DEBUG] plugin: plugin exited --- PASS: TestClient_testInterface (0.01s) === RUN TestClient_grpc_servercrash -2024-05-20T11:50:23.491Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-20T11:50:23.491Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345075 -2024-05-20T11:50:23.491Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.496Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.496Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin751886045 network=unix timestamp=2024-05-20T11:50:23.496Z -2024-05-20T11:50:23.498Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345075 error="signal: killed" -2024-05-20T11:50:23.498Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:45.804Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2025-06-22T18:14:45.805Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045297 +2025-06-22T18:14:45.805Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.809Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3068695299 network=unix timestamp=2025-06-22T18:14:45.809Z +2025-06-22T18:14:45.809Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.810Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045297 error="signal: killed" +2025-06-22T18:14:45.810Z [DEBUG] plugin: plugin exited --- PASS: TestClient_grpc_servercrash (0.01s) === RUN TestClient_grpc -2024-05-20T11:50:23.498Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-20T11:50:23.499Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345081 -2024-05-20T11:50:23.499Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.504Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.504Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3421389099 network=unix timestamp=2024-05-20T11:50:23.504Z -2024-05-20T11:50:23.509Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345081 -2024-05-20T11:50:23.509Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:45.810Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2025-06-22T18:14:45.810Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045304 +2025-06-22T18:14:45.810Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.815Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.815Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2323090938 network=unix timestamp=2025-06-22T18:14:45.814Z +2025-06-22T18:14:45.816Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045304 +2025-06-22T18:14:45.816Z [DEBUG] plugin: plugin exited --- PASS: TestClient_grpc (0.01s) === RUN TestClient_grpcNotAllowed -2024-05-20T11:50:23.509Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-20T11:50:23.509Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345089 -2024-05-20T11:50:23.510Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.515Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.515Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3735278604 network=unix timestamp=2024-05-20T11:50:23.515Z -2024-05-20T11:50:23.515Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:23.518Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345089 error="signal: killed" +2025-06-22T18:14:45.816Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2025-06-22T18:14:45.817Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045312 +2025-06-22T18:14:45.817Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.820Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3236398006 network=unix timestamp=2025-06-22T18:14:45.820Z +2025-06-22T18:14:45.820Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.820Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:45.821Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045312 error="signal: killed" --- PASS: TestClient_grpcNotAllowed (0.01s) === RUN TestClient_cmdAndReattach --- PASS: TestClient_cmdAndReattach (0.00s) === RUN TestClient_reattach -2024-05-20T11:50:23.518Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-20T11:50:23.518Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345095 -2024-05-20T11:50:23.518Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:23.526Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:23.526Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1611539284 network=unix timestamp=2024-05-20T11:50:23.525Z -2024-05-20T11:50:23.528Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:23 [ERR] plugin: plugin server: accept unix /tmp/plugin1611539284: use of closed network connection -2024-05-20T11:50:23.529Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345095 -2024-05-20T11:50:24.526Z [DEBUG] plugin: reattached plugin process exited -2024-05-20T11:50:24.526Z [DEBUG] plugin: plugin exited -2024-05-20T11:50:24.526Z [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-20T11:50:24.527Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:45.822Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2025-06-22T18:14:45.822Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045318 +2025-06-22T18:14:45.822Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:45.827Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:45.827Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin272801621 network=unix timestamp=2025-06-22T18:14:45.827Z +2025-06-22T18:14:45.828Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:45 [ERR] plugin: plugin server: accept unix /tmp/plugin272801621: use of closed network connection +2025-06-22T18:14:45.829Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045318 +2025-06-22T18:14:46.832Z [DEBUG] plugin: reattached plugin process exited +2025-06-22T18:14:46.832Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:46.832Z [WARN] plugin: error closing client during Kill: err="connection is shut down" +2025-06-22T18:14:46.832Z [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_reattach (1.01s) === RUN TestClient_reattachNoProtocol -2024-05-20T11:50:24.527Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-20T11:50:24.527Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345132 -2024-05-20T11:50:24.527Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:24.535Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:24.535Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin147693100 network=unix timestamp=2024-05-20T11:50:24.534Z -2024-05-20T11:50:24.537Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:24 [ERR] plugin: plugin server: accept unix /tmp/plugin147693100: use of closed network connection -2024-05-20T11:50:24.539Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345132 -2024-05-20T11:50:25.536Z [DEBUG] plugin: reattached plugin process exited -2024-05-20T11:50:25.536Z [DEBUG] plugin: plugin exited -2024-05-20T11:50:25.536Z [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-20T11:50:25.536Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:46.832Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2025-06-22T18:14:46.832Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045361 +2025-06-22T18:14:46.832Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:46.837Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2394352881 network=unix timestamp=2025-06-22T18:14:46.837Z +2025-06-22T18:14:46.837Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:46.839Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:46 [ERR] plugin: plugin server: accept unix /tmp/plugin2394352881: use of closed network connection +2025-06-22T18:14:46.894Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045361 +2025-06-22T18:14:47.838Z [DEBUG] plugin: reattached plugin process exited +2025-06-22T18:14:47.838Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:47.838Z [WARN] plugin: error closing client during Kill: err="connection is shut down" +2025-06-22T18:14:47.838Z [WARN] plugin: plugin failed to exit gracefully --- PASS: TestClient_reattachNoProtocol (1.01s) === RUN TestClient_reattachGRPC -2024-05-20T11:50:25.536Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] -2024-05-20T11:50:25.542Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345186 -2024-05-20T11:50:25.542Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:25.548Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:25.549Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3703301549 network=unix timestamp=2024-05-20T11:50:25.548Z -2024-05-20T11:50:25.553Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345186 -2024-05-20T11:50:26.550Z [DEBUG] plugin: reattached plugin process exited -2024-05-20T11:50:26.550Z [DEBUG] plugin: plugin exited -2024-05-20T11:50:26.550Z [DEBUG] plugin: plugin exited ---- PASS: TestClient_reattachGRPC (1.01s) +2025-06-22T18:14:47.839Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc] +2025-06-22T18:14:47.839Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045409 +2025-06-22T18:14:47.839Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:47.864Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3520579676 network=unix timestamp=2025-06-22T18:14:47.864Z +2025-06-22T18:14:47.864Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:47.866Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045409 +2025-06-22T18:14:48.865Z [DEBUG] plugin: reattached plugin process exited +2025-06-22T18:14:48.865Z [DEBUG] plugin: plugin exited +2025-06-22T18:14:48.865Z [DEBUG] plugin: plugin exited +--- PASS: TestClient_reattachGRPC (1.03s) === RUN TestClient_reattachNotFound --- PASS: TestClient_reattachNotFound (0.01s) === RUN TestClientStart_badVersion -2024-05-20T11:50:26.563Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version] -2024-05-20T11:50:26.564Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345267 -2024-05-20T11:50:26.564Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.570Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:26.571Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345267 error="signal: killed" +2025-06-22T18:14:48.880Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version] +2025-06-22T18:14:48.881Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045461 +2025-06-22T18:14:48.881Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:48.885Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:48.885Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045461 error="signal: killed" --- PASS: TestClientStart_badVersion (0.01s) === RUN TestClientStart_badNegotiatedVersion -2024-05-20T11:50:26.571Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] -2024-05-20T11:50:26.571Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345273 -2024-05-20T11:50:26.571Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.579Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2683201196 network=unix timestamp=2024-05-20T11:50:26.579Z -Incompatible API version with plugin. Plugin version: 2, Client versions: [1] -2024-05-20T11:50:26.579Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:26.581Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345273 error="signal: killed" ---- PASS: TestClientStart_badNegotiatedVersion (0.01s) +2025-06-22T18:14:48.886Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] +2025-06-22T18:14:48.886Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045467 +2025-06-22T18:14:48.886Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +timeout while waiting for plugin to start +2025-06-22T18:14:48.936Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:49.044Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045467 error="signal: killed" +--- PASS: TestClientStart_badNegotiatedVersion (0.16s) === RUN TestClient_Start_Timeout -2024-05-20T11:50:26.581Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout] -2024-05-20T11:50:26.582Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345279 -2024-05-20T11:50:26.582Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.632Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:26.633Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345279 error="signal: killed" +2025-06-22T18:14:49.044Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout] +2025-06-22T18:14:49.044Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045474 +2025-06-22T18:14:49.044Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:49.095Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:49.095Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045474 error="signal: killed" --- PASS: TestClient_Start_Timeout (0.05s) === RUN TestClient_Stderr -2024-05-20T11:50:26.646Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr] -2024-05-20T11:50:26.646Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345287 -2024-05-20T11:50:26.646Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.651Z [DEBUG] plugin.go-plugin.test: HELLO -2024-05-20T11:50:26.651Z [DEBUG] plugin.go-plugin.test: WORLD -2024-05-20T11:50:26.651Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:26.652Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345287 -2024-05-20T11:50:26.663Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2024-05-20T11:50:26.663Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:49.095Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr] +2025-06-22T18:14:49.096Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045512 +2025-06-22T18:14:49.096Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:49.100Z [DEBUG] plugin.go-plugin.test: HELLO +2025-06-22T18:14:49.100Z [DEBUG] plugin.go-plugin.test: WORLD +2025-06-22T18:14:49.100Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:49.100Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045512 +2025-06-22T18:14:49.110Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2025-06-22T18:14:49.110Z [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) +--- PASS: TestClient_textLogLevel (0.01s) === RUN TestClient_Stdin -2024-05-20T11:50:26.702Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin] -2024-05-20T11:50:26.702Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345305 -2024-05-20T11:50:26.702Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.708Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:26.709Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345305 -2024-05-20T11:50:26.759Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" -2024-05-20T11:50:26.759Z [WARN] plugin: plugin failed to exit gracefully ---- PASS: TestClient_Stdin (0.06s) +2025-06-22T18:14:49.686Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin] +2025-06-22T18:14:49.686Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045638 +2025-06-22T18:14:49.686Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:49.691Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:49.948Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045638 +2025-06-22T18:14:49.994Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused" +2025-06-22T18:14:49.994Z [WARN] plugin: plugin failed to exit gracefully +--- PASS: TestClient_Stdin (0.90s) === RUN TestClient_SecureConfig -2024-05-20T11:50:26.975Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-20T11:50:26.975Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345325 -2024-05-20T11:50:26.975Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.982Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:26.982Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2180753915 network=unix timestamp=2024-05-20T11:50:26.981Z -2024-05-20T11:50:26.984Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:26 [ERR] plugin: plugin server: accept unix /tmp/plugin2180753915: use of closed network connection -2024-05-20T11:50:26.985Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345325 -2024-05-20T11:50:26.985Z [DEBUG] plugin: plugin exited ---- PASS: TestClient_SecureConfig (0.23s) +2025-06-22T18:14:50.141Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2025-06-22T18:14:50.141Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045770 +2025-06-22T18:14:50.141Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:50.189Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:50.189Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3523525727 network=unix timestamp=2025-06-22T18:14:50.188Z +2025-06-22T18:14:50.189Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:50 [ERR] plugin: plugin server: accept unix /tmp/plugin3523525727: use of closed network connection +2025-06-22T18:14:50.190Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045770 +2025-06-22T18:14:50.190Z [DEBUG] plugin: plugin exited +--- PASS: TestClient_SecureConfig (0.15s) === RUN TestClient_TLS client_test.go:820: DM-skipped --- SKIP: TestClient_TLS (0.00s) === RUN TestClient_TLS_grpc -2024-05-20T11:50:26.986Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls] -2024-05-20T11:50:26.986Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345333 -2024-05-20T11:50:26.986Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:26.992Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:26.992Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4049746107 network=unix timestamp=2024-05-20T11:50:26.992Z -2024-05-20T11:50:27.006Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345333 -2024-05-20T11:50:27.006Z [DEBUG] plugin: plugin exited ---- PASS: TestClient_TLS_grpc (0.02s) +2025-06-22T18:14:50.191Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls] +2025-06-22T18:14:50.191Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045781 +2025-06-22T18:14:50.191Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:50.290Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:50.290Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1103109023 network=unix timestamp=2025-06-22T18:14:50.289Z +2025-06-22T18:14:50.297Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045781 +2025-06-22T18:14:50.297Z [DEBUG] plugin: plugin exited +--- PASS: TestClient_TLS_grpc (0.11s) === RUN TestClient_secureConfigAndReattach --- PASS: TestClient_secureConfigAndReattach (0.00s) === RUN TestClient_ping -2024-05-20T11:50:27.006Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] -2024-05-20T11:50:27.007Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345341 -2024-05-20T11:50:27.007Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:27.014Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:27.014Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin178821104 network=unix timestamp=2024-05-20T11:50:27.014Z -2024-05-20T11:50:27.016Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:27 [ERR] plugin: plugin server: accept unix /tmp/plugin178821104: use of closed network connection -2024-05-20T11:50:27.018Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345341 -2024-05-20T11:50:27.018Z [DEBUG] plugin: plugin exited ---- PASS: TestClient_ping (0.01s) +2025-06-22T18:14:50.298Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface] +2025-06-22T18:14:50.298Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045800 +2025-06-22T18:14:50.298Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:50.376Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:50.376Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2733053980 network=unix timestamp=2025-06-22T18:14:50.376Z +2025-06-22T18:14:50.378Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:50 [ERR] plugin: plugin server: accept unix /tmp/plugin2733053980: use of closed network connection +2025-06-22T18:14:50.379Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045800 +2025-06-22T18:14:50.379Z [DEBUG] plugin: plugin exited +--- PASS: TestClient_ping (0.08s) === RUN TestClient_wrongVersion -2024-05-20T11:50:27.018Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] -2024-05-20T11:50:27.018Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345349 -2024-05-20T11:50:27.018Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:27.025Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:27.025Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2664451214 network=unix timestamp=2024-05-20T11:50:27.025Z -2024-05-20T11:50:27.025Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:27.026Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345349 error="signal: killed" +2025-06-22T18:14:50.379Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] +2025-06-22T18:14:50.379Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045811 +2025-06-22T18:14:50.380Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:50.385Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:50.385Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4112381995 network=unix timestamp=2025-06-22T18:14:50.385Z +2025-06-22T18:14:50.385Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:50.385Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045811 error="signal: killed" --- PASS: TestClient_wrongVersion (0.01s) === RUN TestClient_legacyClient -2024-05-20T11:50:27.026Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] -2024-05-20T11:50:27.027Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345355 -2024-05-20T11:50:27.027Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:27.032Z [DEBUG] plugin: using plugin: version=1 -2024-05-20T11:50:27.032Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin64912940 network=unix timestamp=2024-05-20T11:50:27.032Z -2024-05-20T11:50:27.033Z [DEBUG] plugin.go-plugin.test: 2024/05/20 11:50:27 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake -2024-05-20T11:50:27.033Z [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-20T11:50:27.033Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:27.034Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345355 error="signal: killed" +2025-06-22T18:14:50.385Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin] +2025-06-22T18:14:50.386Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045827 +2025-06-22T18:14:50.386Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:50.389Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin898465009 network=unix timestamp=2025-06-22T18:14:50.389Z +2025-06-22T18:14:50.390Z [DEBUG] plugin: using plugin: version=1 +2025-06-22T18:14:50.390Z [DEBUG] plugin.go-plugin.test: 2025/06/22 18:14:50 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake +2025-06-22T18:14:50.390Z [WARN] plugin: error closing client during Kill: err="connection is shut down" +2025-06-22T18:14:50.390Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:50.390Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045827 error="signal: killed" --- PASS: TestClient_legacyClient (0.01s) === RUN TestClient_legacyServer -2024-05-20T11:50:27.035Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client] -2024-05-20T11:50:27.035Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345362 -2024-05-20T11:50:27.035Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:27.043Z [DEBUG] plugin: using plugin: version=2 -2024-05-20T11:50:27.043Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin86257190 network=unix timestamp=2024-05-20T11:50:27.043Z -2024-05-20T11:50:29.045Z [WARN] plugin: plugin failed to exit gracefully -2024-05-20T11:50:29.047Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345362 error="signal: killed" ---- PASS: TestClient_legacyServer (2.01s) +2025-06-22T18:14:50.391Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client] +2025-06-22T18:14:50.391Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045840 +2025-06-22T18:14:50.391Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:50.454Z [DEBUG] plugin: using plugin: version=2 +2025-06-22T18:14:50.454Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin874339865 network=unix timestamp=2025-06-22T18:14:50.454Z +2025-06-22T18:14:52.455Z [WARN] plugin: plugin failed to exit gracefully +2025-06-22T18:14:52.456Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4045840 error="signal: killed" +--- PASS: TestClient_legacyServer (2.07s) === RUN TestClient_versionedClient -2024-05-20T11:50:29.047Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] -2024-05-20T11:50:29.047Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345413 -2024-05-20T11:50:29.047Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:29.054Z [DEBUG] plugin: using plugin: version=2 -2024-05-20T11:50:29.054Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4010740950 network=unix timestamp=2024-05-20T11:50:29.054Z -2024-05-20T11:50:29.055Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345413 error="signal: killed" -2024-05-20T11:50:29.056Z [DEBUG] plugin: plugin exited ---- PASS: TestClient_versionedClient (0.01s) +2025-06-22T18:14:52.456Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins] +2025-06-22T18:14:52.457Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4046085 +2025-06-22T18:14:52.457Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:52.510Z [DEBUG] plugin: using plugin: version=2 +2025-06-22T18:14:52.510Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3558708269 network=unix timestamp=2025-06-22T18:14:52.510Z +2025-06-22T18:14:52.511Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4046085 error="signal: killed" +2025-06-22T18:14:52.511Z [DEBUG] plugin: plugin exited +--- PASS: TestClient_versionedClient (0.05s) === RUN TestClient_mtlsClient -2024-05-20T11:50:29.056Z [INFO] plugin: configuring client automatic mTLS -2024-05-20T11:50:29.080Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls] -2024-05-20T11:50:29.080Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345420 -2024-05-20T11:50:29.080Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:29.086Z [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2024-05-20T11:50:29.086Z -2024-05-20T11:50:29.109Z [DEBUG] plugin: using plugin: version=2 -2024-05-20T11:50:29.109Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin261318128 network=unix timestamp=2024-05-20T11:50:29.109Z -2024-05-20T11:50:29.129Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345420 error="signal: killed" -2024-05-20T11:50:29.129Z [DEBUG] plugin: plugin exited ---- PASS: TestClient_mtlsClient (0.07s) +2025-06-22T18:14:52.511Z [INFO] plugin: configuring client automatic mTLS +2025-06-22T18:14:52.525Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls] +2025-06-22T18:14:52.526Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4046104 +2025-06-22T18:14:52.526Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:52.531Z [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2025-06-22T18:14:52.531Z +2025-06-22T18:14:52.545Z [DEBUG] plugin: using plugin: version=2 +2025-06-22T18:14:52.545Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1702844849 network=unix timestamp=2025-06-22T18:14:52.545Z +2025-06-22T18:14:52.556Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4046104 error="signal: killed" +2025-06-22T18:14:52.556Z [DEBUG] plugin: plugin exited +--- PASS: TestClient_mtlsClient (0.04s) === RUN TestClient_mtlsNetRPCClient -2024-05-20T11:50:29.129Z [INFO] plugin: configuring client automatic mTLS -2024-05-20T11:50:29.138Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2865261539/b001/go-plugin.test args=[/tmp/go-build2865261539/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls] -2024-05-20T11:50:29.138Z [DEBUG] plugin: plugin started: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345428 -2024-05-20T11:50:29.138Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2865261539/b001/go-plugin.test -2024-05-20T11:50:29.144Z [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2024-05-20T11:50:29.144Z -2024-05-20T11:50:29.167Z [DEBUG] plugin: using plugin: version=0 -2024-05-20T11:50:29.167Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin858928778 network=unix timestamp=2024-05-20T11:50:29.167Z -2024-05-20T11:50:29.186Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2865261539/b001/go-plugin.test pid=3345428 error="signal: killed" -2024-05-20T11:50:29.186Z [WARN] plugin: error closing client during Kill: err="connection is shut down" -2024-05-20T11:50:29.186Z [WARN] plugin: plugin failed to exit gracefully ---- PASS: TestClient_mtlsNetRPCClient (0.06s) +2025-06-22T18:14:52.556Z [INFO] plugin: configuring client automatic mTLS +2025-06-22T18:14:52.562Z [DEBUG] plugin: starting plugin: path=/tmp/go-build2277955547/b001/go-plugin.test args=[/tmp/go-build2277955547/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls] +2025-06-22T18:14:52.562Z [DEBUG] plugin: plugin started: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4046118 +2025-06-22T18:14:52.562Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build2277955547/b001/go-plugin.test +2025-06-22T18:14:52.571Z [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2025-06-22T18:14:52.571Z +2025-06-22T18:14:52.585Z [DEBUG] plugin: using plugin: version=0 +2025-06-22T18:14:52.585Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2298713922 network=unix timestamp=2025-06-22T18:14:52.585Z +2025-06-22T18:14:52.596Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build2277955547/b001/go-plugin.test pid=4046118 error="signal: killed" +2025-06-22T18:14:52.596Z [WARN] plugin: error closing client during Kill: err="connection is shut down" +2025-06-22T18:14:52.596Z [WARN] plugin: plugin failed to exit gracefully +--- PASS: TestClient_mtlsNetRPCClient (0.04s) === RUN TestClient_logger client_test.go:1215: flakky tests, #1027551 --- SKIP: TestClient_logger (0.00s) === RUN TestClient_logStderr -2024-05-20T11:50:29.186Z [DEBUG] plugin.test: -2024-05-20T11:50:29.186Z [DEBUG] plugin.test: this line is more than 32 bytes -2024-05-20T11:50:29.186Z [DEBUG] plugin.test: long -2024-05-20T11:50:29.187Z [DEBUG] plugin.test: and this line is more than 32 by -2024-05-20T11:50:29.187Z [DEBUG] plugin.test: tes long -2024-05-20T11:50:29.187Z [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z -2024-05-20T11:50:29.187Z [DEBUG] plugin.test: this line is short +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: this line is more than 32 bytes +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: long +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: and this line is more than 32 by +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: tes long +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z +2025-06-22T18:14:52.596Z [DEBUG] plugin.test: this line is short --- PASS: TestClient_logStderr (0.00s) === RUN TestBasicError_ImplementsError --- PASS: TestBasicError_ImplementsError (0.00s) @@ -1102,7 +1136,7 @@ === RUN TestProtocolSelection_no_server --- PASS: TestProtocolSelection_no_server (0.00s) PASS -ok github.com/hashicorp/go-plugin 7.772s +ok github.com/hashicorp/go-plugin 8.835s make[1]: Leaving directory '/build/reproducible-path/golang-github-hashicorp-go-plugin-1.0.1' create-stamp debian/debhelper-build-stamp fakeroot debian/rules binary @@ -1134,12 +1168,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/4032177/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/4032177/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/3287174 and its subdirectories -I: Current time: Sun May 19 23:50:37 -12 2024 -I: pbuilder-time-stamp: 1716205837 +I: removing directory /srv/workspace/pbuilder/4032177 and its subdirectories +I: Current time: Mon Jun 23 08:15:01 +14 2025 +I: pbuilder-time-stamp: 1750616101