[pkg-go] Bug#1008434: golang-github-hashicorp-go-plugin: FTBFS: dh_auto_test: error: cd obj-x86_64-linux-gnu && go test -vet=off -v -p 8 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin returned exit code 1
Lucas Nussbaum
lucas at debian.org
Sat Mar 26 21:15:57 GMT 2022
Source: golang-github-hashicorp-go-plugin
Version: 1.0.1-3
Severity: serious
Justification: FTBFS
Tags: bookworm sid ftbfs
User: lucas at debian.org
Usertags: ftbfs-20220326 ftbfs-bookworm
Hi,
During a rebuild of all packages in sid, your package failed to build
on amd64.
Relevant part (hopefully):
> make[1]: Entering directory '/<<PKGBUILDDIR>>'
> DH_GOLANG_EXCLUDES="examples test/grpc" \
> dh_auto_test
> cd obj-x86_64-linux-gnu && go test -vet=off -v -p 8 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin
> === RUN TestClient_testInterfaceReattach
> 2022-03-26T19:39:12.362Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-daemon]
> 2022-03-26T19:39:12.362Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239565
> 2022-03-26T19:39:12.362Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:12.367Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1699243854 network=unix timestamp=2022-03-26T19:39:12.367Z
> 2022-03-26T19:39:12.367Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:13.369Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:13 [ERR] plugin: plugin server: accept unix /tmp/plugin1699243854: use of closed network connection
> 2022-03-26T19:39:13.370Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239565
> 2022-03-26T19:39:14.367Z [DEBUG] plugin: reattached plugin process exited
> 2022-03-26T19:39:14.367Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_testInterfaceReattach (2.01s)
> === RUN TestClient
> 2022-03-26T19:39:14.367Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, mock]
> 2022-03-26T19:39:14.368Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239573
> 2022-03-26T19:39:14.368Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.373Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.373Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused"
> 2022-03-26T19:39:14.373Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:14.373Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239573 error="signal: killed"
> --- PASS: TestClient (0.01s)
> === RUN TestClient_killStart
> 2022-03-26T19:39:14.374Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version, /tmp/plugin2389898487/booted]
> 2022-03-26T19:39:14.374Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239579
> 2022-03-26T19:39:14.374Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.379Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:14.379Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239579 error="signal: killed"
> --- PASS: TestClient_killStart (0.01s)
> === RUN TestClient_testCleanup
> 2022-03-26T19:39:14.380Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, cleanup, /tmp/plugin3397144587/output]
> 2022-03-26T19:39:14.380Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239585
> 2022-03-26T19:39:14.380Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.385Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.386Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin522920750 network=unix timestamp=2022-03-26T19:39:14.385Z
> 2022-03-26T19:39:14.386Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:14 [ERR] plugin: plugin server: accept unix /tmp/plugin522920750: use of closed network connection
> 2022-03-26T19:39:14.387Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239585
> 2022-03-26T19:39:14.387Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_testCleanup (0.01s)
> === RUN TestClient_testInterface
> 2022-03-26T19:39:14.387Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface]
> 2022-03-26T19:39:14.387Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239593
> 2022-03-26T19:39:14.387Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.392Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3098807932 network=unix timestamp=2022-03-26T19:39:14.392Z
> 2022-03-26T19:39:14.392Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.394Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:14 [ERR] plugin: plugin server: accept unix /tmp/plugin3098807932: use of closed network connection
> 2022-03-26T19:39:14.395Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239593
> 2022-03-26T19:39:14.395Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_testInterface (0.01s)
> === RUN TestClient_grpc_servercrash
> 2022-03-26T19:39:14.395Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc]
> 2022-03-26T19:39:14.395Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239600
> 2022-03-26T19:39:14.395Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.400Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2538498054 network=unix timestamp=2022-03-26T19:39:14.399Z
> 2022-03-26T19:39:14.400Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.401Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239600 error="signal: killed"
> 2022-03-26T19:39:14.401Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_grpc_servercrash (0.01s)
> === RUN TestClient_grpc
> 2022-03-26T19:39:14.401Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc]
> 2022-03-26T19:39:14.401Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239607
> 2022-03-26T19:39:14.401Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.407Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1603168123 network=unix timestamp=2022-03-26T19:39:14.407Z
> 2022-03-26T19:39:14.407Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.409Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239607
> 2022-03-26T19:39:14.409Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_grpc (0.01s)
> === RUN TestClient_grpcNotAllowed
> 2022-03-26T19:39:14.409Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc]
> 2022-03-26T19:39:14.409Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239616
> 2022-03-26T19:39:14.409Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.414Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.414Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:14.414Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2931964128 network=unix timestamp=2022-03-26T19:39:14.414Z
> 2022-03-26T19:39:14.415Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239616 error="signal: killed"
> --- PASS: TestClient_grpcNotAllowed (0.01s)
> === RUN TestClient_cmdAndReattach
> --- PASS: TestClient_cmdAndReattach (0.00s)
> === RUN TestClient_reattach
> 2022-03-26T19:39:14.415Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface]
> 2022-03-26T19:39:14.415Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239622
> 2022-03-26T19:39:14.415Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:14.422Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:14.423Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3995156065 network=unix timestamp=2022-03-26T19:39:14.419Z
> 2022-03-26T19:39:14.427Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:14 [ERR] plugin: plugin server: accept unix /tmp/plugin3995156065: use of closed network connection
> 2022-03-26T19:39:14.431Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239622
> 2022-03-26T19:39:15.424Z [DEBUG] plugin: reattached plugin process exited
> 2022-03-26T19:39:15.424Z [DEBUG] plugin: plugin exited
> 2022-03-26T19:39:15.424Z [WARN] plugin: error closing client during Kill: err="connection is shut down"
> 2022-03-26T19:39:15.424Z [WARN] plugin: plugin failed to exit gracefully
> --- PASS: TestClient_reattach (1.01s)
> === RUN TestClient_reattachNoProtocol
> 2022-03-26T19:39:15.424Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface]
> 2022-03-26T19:39:15.424Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239631
> 2022-03-26T19:39:15.424Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:15.432Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2411208388 network=unix timestamp=2022-03-26T19:39:15.432Z
> 2022-03-26T19:39:15.432Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:15.434Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:15 [ERR] plugin: plugin server: accept unix /tmp/plugin2411208388: use of closed network connection
> 2022-03-26T19:39:15.435Z [WARN] plugin: error closing client during Kill: err="unexpected EOF"
> 2022-03-26T19:39:15.435Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:15.435Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239631
> 2022-03-26T19:39:16.433Z [DEBUG] plugin: reattached plugin process exited
> client_test.go:431: process failed to exit gracefully
> 2022-03-26T19:39:16.433Z [WARN] plugin: error closing client during Kill: err="connection is shut down"
> 2022-03-26T19:39:16.433Z [WARN] plugin: plugin failed to exit gracefully
> --- FAIL: TestClient_reattachNoProtocol (1.01s)
> === RUN TestClient_reattachGRPC
> 2022-03-26T19:39:16.433Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc]
> 2022-03-26T19:39:16.433Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239640
> 2022-03-26T19:39:16.433Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:16.439Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:16.439Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4164072784 network=unix timestamp=2022-03-26T19:39:16.438Z
> 2022-03-26T19:39:16.442Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239640
> 2022-03-26T19:39:17.439Z [DEBUG] plugin: reattached plugin process exited
> 2022-03-26T19:39:17.439Z [DEBUG] plugin: plugin exited
> 2022-03-26T19:39:17.440Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_reattachGRPC (1.01s)
> === RUN TestClient_reattachNotFound
> --- PASS: TestClient_reattachNotFound (0.01s)
> === RUN TestClientStart_badVersion
> 2022-03-26T19:39:17.453Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, bad-version]
> 2022-03-26T19:39:17.453Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239650
> 2022-03-26T19:39:17.453Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.465Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:17.465Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239650 error="signal: killed"
> --- PASS: TestClientStart_badVersion (0.01s)
> === RUN TestClientStart_badNegotiatedVersion
> 2022-03-26T19:39:17.465Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins]
> 2022-03-26T19:39:17.466Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239656
> 2022-03-26T19:39:17.466Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> Incompatible API version with plugin. Plugin version: 2, Client versions: [1]
> 2022-03-26T19:39:17.474Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:17.474Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2578353829 network=unix timestamp=2022-03-26T19:39:17.474Z
> 2022-03-26T19:39:17.475Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239656 error="signal: killed"
> --- PASS: TestClientStart_badNegotiatedVersion (0.01s)
> === RUN TestClient_Start_Timeout
> 2022-03-26T19:39:17.475Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, start-timeout]
> 2022-03-26T19:39:17.476Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239662
> 2022-03-26T19:39:17.476Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.526Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:17.528Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239662 error="signal: killed"
> --- PASS: TestClient_Start_Timeout (0.05s)
> === RUN TestClient_Stderr
> 2022-03-26T19:39:17.528Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, stderr]
> 2022-03-26T19:39:17.528Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239668
> 2022-03-26T19:39:17.528Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.533Z [DEBUG] plugin.go-plugin.test: HELLO
> 2022-03-26T19:39:17.533Z [DEBUG] plugin.go-plugin.test: WORLD
> 2022-03-26T19:39:17.533Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.534Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239668
> 2022-03-26T19:39:17.544Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused"
> 2022-03-26T19:39:17.544Z [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
> 2022-03-26T19:39:17.585Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, stdin]
> 2022-03-26T19:39:17.585Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239688
> 2022-03-26T19:39:17.585Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.592Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.594Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239688
> 2022-03-26T19:39:17.643Z [ERROR] plugin: client: error="dial tcp :1234: connect: connection refused"
> 2022-03-26T19:39:17.643Z [WARN] plugin: plugin failed to exit gracefully
> --- PASS: TestClient_Stdin (0.06s)
> === RUN TestClient_SecureConfig
> 2022-03-26T19:39:17.796Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface]
> 2022-03-26T19:39:17.797Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239694
> 2022-03-26T19:39:17.797Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.803Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3678507364 network=unix timestamp=2022-03-26T19:39:17.802Z
> 2022-03-26T19:39:17.803Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.805Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:17 [ERR] plugin: plugin server: accept unix /tmp/plugin3678507364: use of closed network connection
> 2022-03-26T19:39:17.806Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239694
> 2022-03-26T19:39:17.806Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_SecureConfig (0.16s)
> === RUN TestClient_TLS
> client_test.go:820: DM-skipped
> --- SKIP: TestClient_TLS (0.00s)
> === RUN TestClient_TLS_grpc
> 2022-03-26T19:39:17.807Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-grpc-tls]
> 2022-03-26T19:39:17.808Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239702
> 2022-03-26T19:39:17.808Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.816Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.816Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin1782586867 network=unix timestamp=2022-03-26T19:39:17.816Z
> 2022-03-26T19:39:17.835Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239702
> 2022-03-26T19:39:17.835Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_TLS_grpc (0.03s)
> === RUN TestClient_secureConfigAndReattach
> --- PASS: TestClient_secureConfigAndReattach (0.00s)
> === RUN TestClient_ping
> 2022-03-26T19:39:17.835Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface]
> 2022-03-26T19:39:17.836Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239710
> 2022-03-26T19:39:17.836Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.840Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin3489548986 network=unix timestamp=2022-03-26T19:39:17.840Z
> 2022-03-26T19:39:17.840Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.842Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:17 [ERR] plugin: plugin server: accept unix /tmp/plugin3489548986: use of closed network connection
> 2022-03-26T19:39:17.846Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239710
> 2022-03-26T19:39:17.846Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_ping (0.01s)
> === RUN TestClient_wrongVersion
> 2022-03-26T19:39:17.846Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin]
> 2022-03-26T19:39:17.847Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239719
> 2022-03-26T19:39:17.847Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.862Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.862Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:17.862Z [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin556035600 timestamp=2022-03-26T19:39:17.861Z
> 2022-03-26T19:39:17.864Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239719 error="signal: killed"
> --- PASS: TestClient_wrongVersion (0.02s)
> === RUN TestClient_legacyClient
> 2022-03-26T19:39:17.864Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-plugin]
> 2022-03-26T19:39:17.864Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239726
> 2022-03-26T19:39:17.864Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.872Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin397023649 network=unix timestamp=2022-03-26T19:39:17.872Z
> 2022-03-26T19:39:17.872Z [DEBUG] plugin: using plugin: version=1
> 2022-03-26T19:39:17.873Z [WARN] plugin: error closing client during Kill: err="connection is shut down"
> 2022-03-26T19:39:17.873Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:17.873Z [DEBUG] plugin.go-plugin.test: 2022/03/26 19:39:17 [ERR] plugin: error accepting control connection: tls: first record does not look like a TLS handshake
> 2022-03-26T19:39:17.874Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239726 error="signal: killed"
> --- PASS: TestClient_legacyClient (0.01s)
> === RUN TestClient_legacyServer
> 2022-03-26T19:39:17.874Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-proto-upgraded-client]
> 2022-03-26T19:39:17.874Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239733
> 2022-03-26T19:39:17.874Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:17.881Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2841830651 network=unix timestamp=2022-03-26T19:39:17.881Z
> 2022-03-26T19:39:17.881Z [DEBUG] plugin: using plugin: version=2
> 2022-03-26T19:39:19.883Z [WARN] plugin: plugin failed to exit gracefully
> 2022-03-26T19:39:19.883Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239733 error="signal: killed"
> --- PASS: TestClient_legacyServer (2.01s)
> === RUN TestClient_versionedClient
> 2022-03-26T19:39:19.884Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-versioned-plugins]
> 2022-03-26T19:39:19.884Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239740
> 2022-03-26T19:39:19.884Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:19.890Z [DEBUG] plugin: using plugin: version=2
> 2022-03-26T19:39:19.890Z [DEBUG] plugin.go-plugin.test: plugin address: network=unix address=/tmp/plugin3537877433 timestamp=2022-03-26T19:39:19.890Z
> 2022-03-26T19:39:19.891Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239740 error="signal: killed"
> 2022-03-26T19:39:19.891Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_versionedClient (0.01s)
> === RUN TestClient_mtlsClient
> 2022-03-26T19:39:19.891Z [INFO] plugin: configuring client automatic mTLS
> 2022-03-26T19:39:19.926Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-mtls]
> 2022-03-26T19:39:19.927Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239746
> 2022-03-26T19:39:19.927Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:19.931Z [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2022-03-26T19:39:19.931Z
> 2022-03-26T19:39:19.957Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin2739828635 network=unix timestamp=2022-03-26T19:39:19.956Z
> 2022-03-26T19:39:19.957Z [DEBUG] plugin: using plugin: version=2
> 2022-03-26T19:39:19.999Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239746 error="signal: killed"
> 2022-03-26T19:39:19.999Z [DEBUG] plugin: plugin exited
> --- PASS: TestClient_mtlsClient (0.11s)
> === RUN TestClient_mtlsNetRPCClient
> 2022-03-26T19:39:19.999Z [INFO] plugin: configuring client automatic mTLS
> 2022-03-26T19:39:20.036Z [DEBUG] plugin: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-mtls]
> 2022-03-26T19:39:20.037Z [DEBUG] plugin: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239755
> 2022-03-26T19:39:20.038Z [DEBUG] plugin: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:20.046Z [INFO] plugin.go-plugin.test: configuring server automatic mTLS: timestamp=2022-03-26T19:39:20.046Z
> 2022-03-26T19:39:20.072Z [DEBUG] plugin: using plugin: version=0
> 2022-03-26T19:39:20.072Z [DEBUG] plugin.go-plugin.test: plugin address: address=/tmp/plugin4262656345 network=unix timestamp=2022-03-26T19:39:20.072Z
> 2022-03-26T19:39:20.107Z [DEBUG] plugin: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239755 error="signal: killed"
> 2022-03-26T19:39:20.107Z [WARN] plugin: error closing client during Kill: err="session shutdown"
> 2022-03-26T19:39:20.107Z [WARN] plugin: plugin failed to exit gracefully
> --- PASS: TestClient_mtlsNetRPCClient (0.11s)
> === RUN TestClient_logger
> === RUN TestClient_logger/net/rpc
> 2022-03-26T19:39:20.107Z [DEBUG] test-logger: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-logger-netrpc]
> 2022-03-26T19:39:20.108Z [DEBUG] test-logger: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239762
> 2022-03-26T19:39:20.108Z [DEBUG] test-logger: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:20.113Z [DEBUG] test-logger: using plugin: version=1
> 2022-03-26T19:39:20.113Z [DEBUG] test-logger.go-plugin.test: plugin address: address=/tmp/plugin3133704808 network=unix timestamp=2022-03-26T19:39:20.113Z
> 2022-03-26T19:39:20.115Z [INFO] test-logger.go-plugin.test: PrintKV called: foo=bar timestamp=2022-03-26T19:39:20.115Z
> 2022-03-26T19:39:20.216Z [INFO] test-logger.go-plugin.test: PrintKV called: foo=12 timestamp=2022-03-26T19:39:20.216Z
> 2022-03-26T19:39:20.317Z [DEBUG] test-logger.go-plugin.test: 2022/03/26 19:39:20 [ERR] plugin: plugin server: accept unix /tmp/plugin3133704808: use of closed network connection
> 2022-03-26T19:39:20.318Z [DEBUG] test-logger: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239762
> 2022-03-26T19:39:20.318Z [DEBUG] test-logger: plugin exited
> === RUN TestClient_logger/grpc
> 2022-03-26T19:39:20.318Z [DEBUG] test-logger: starting plugin: path=/tmp/go-build1311877249/b001/go-plugin.test args=[/tmp/go-build1311877249/b001/go-plugin.test, -test.run=TestHelperProcess, --, test-interface-logger-grpc]
> 2022-03-26T19:39:20.318Z [DEBUG] test-logger: plugin started: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239770
> 2022-03-26T19:39:20.318Z [DEBUG] test-logger: waiting for RPC address: path=/tmp/go-build1311877249/b001/go-plugin.test
> 2022-03-26T19:39:20.326Z [DEBUG] test-logger.go-plugin.test: plugin address: address=/tmp/plugin75360428 network=unix timestamp=2022-03-26T19:39:20.325Z
> 2022-03-26T19:39:20.331Z [DEBUG] test-logger: using plugin: version=1
> 2022-03-26T19:39:20.333Z [INFO] test-logger.go-plugin.test: PrintKV called: foo=bar timestamp=2022-03-26T19:39:20.333Z
> 2022-03-26T19:39:20.434Z [INFO] test-logger.go-plugin.test: PrintKV called: foo=12 timestamp=2022-03-26T19:39:20.433Z
> 2022-03-26T19:39:20.534Z [DEBUG] test-logger.go-plugin.test: 2022/03/26 19:39:20 [ERR] plugin: plugin server: accept unix /tmp/plugin75360428: use of closed network connection
> 2022-03-26T19:39:20.535Z [DEBUG] test-logger: plugin process exited: path=/tmp/go-build1311877249/b001/go-plugin.test pid=1239770
> 2022-03-26T19:39:20.535Z [DEBUG] test-logger: plugin exited
> --- PASS: TestClient_logger (0.43s)
> --- PASS: TestClient_logger/net/rpc (0.21s)
> --- PASS: TestClient_logger/grpc (0.22s)
> === RUN TestClient_logStderr
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test:
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test: this line is more than 32 bytes
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test: long
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test: and this line is more than 32 by
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test: tes long
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test: : a=b timestamp=0001-01-01T00:00:00.000Z
> 2022-03-26T19:39:20.535Z [DEBUG] plugin.test: this line is short
> --- PASS: TestClient_logStderr (0.00s)
> === RUN TestBasicError_ImplementsError
> --- PASS: TestBasicError_ImplementsError (0.00s)
> === RUN TestBasicError_MatchesMessage
> --- PASS: TestBasicError_MatchesMessage (0.00s)
> === RUN TestNewBasicError_nil
> --- PASS: TestNewBasicError_nil (0.00s)
> === RUN TestGRPCClient_App
> --- PASS: TestGRPCClient_App (0.00s)
> === RUN TestGRPCConn_BidirectionalPing
> --- PASS: TestGRPCConn_BidirectionalPing (0.00s)
> === RUN TestGRPCC_Stream
> --- PASS: TestGRPCC_Stream (0.00s)
> === RUN TestGRPCClient_Ping
> --- PASS: TestGRPCClient_Ping (0.00s)
> === RUN TestHelperProcess
> --- PASS: TestHelperProcess (0.00s)
> === RUN TestClient_App
> --- PASS: TestClient_App (0.00s)
> === RUN TestClient_syncStreams
> rpc_client_test.go:47: Disabled unreliable test
> --- SKIP: TestClient_syncStreams (0.00s)
> === RUN TestRmListener_impl
> --- PASS: TestRmListener_impl (0.00s)
> === RUN TestRmListener
> --- PASS: TestRmListener (0.00s)
> === RUN TestProtocolSelection_no_server
> --- PASS: TestProtocolSelection_no_server (0.00s)
> FAIL
> FAIL github.com/hashicorp/go-plugin 8.191s
> ? github.com/hashicorp/go-plugin/internal/plugin [no test files]
> FAIL
> dh_auto_test: error: cd obj-x86_64-linux-gnu && go test -vet=off -v -p 8 github.com/hashicorp/go-plugin github.com/hashicorp/go-plugin/internal/plugin returned exit code 1
The full build log is available from:
http://qa-logs.debian.net/2022/03/26/golang-github-hashicorp-go-plugin_1.0.1-3_unstable.log
A list of current common problems and possible solutions is available at
http://wiki.debian.org/qa.debian.org/FTBFS . You're welcome to contribute!
If you reassign this bug to another package, please marking it as 'affects'-ing
this package. See https://www.debian.org/Bugs/server-control#affects
If you fail to reproduce this, please provide a build log and diff it with mine
so that we can identify if something relevant changed in the meantime.
More information about the Pkg-go-maintainers
mailing list