[I2024-07-26T18:25:50.499735Z 524424 0 sink.go:276] SinkServer: warm-up started
[I2024-07-26T18:25:50.499784Z 524424 0 sink.go:346] SinkServer: starting HTTP server...
[I2024-07-26T18:25:50.501489Z 524424 0 sink.go:281] SinkServer: warm-up ended
[I2024-07-26T18:25:50.501729Z 524424 0 cmd_stream.go:492] rdb-stream: starting the test command - ["/home/swarming/.swarming/w/ir/cache/tools/bin/result_adapter" "go" "-v=false" "-dump-json" "/home/swarming/.swarming/w/ir/x/w/go.testjson" "--" "/home/swarming/.swarming/w/ir/x/w/goroot/bin/go" "test" "-json" "-short" "./..."]
ok  	golang.org/x/tools/benchmark/parse	0.007s
ok  	golang.org/x/tools/blog	0.015s
ok  	golang.org/x/tools/cmd/benchcmp	0.010s
ok  	golang.org/x/tools/cmd/bisect	0.055s
ok  	golang.org/x/tools/cmd/bundle	0.737s
ok  	golang.org/x/tools/cmd/callgraph	6.207s
ok  	golang.org/x/tools/cmd/deadcode	4.400s
ok  	golang.org/x/tools/cmd/digraph	0.007s
ok  	golang.org/x/tools/cmd/file2fuzz	0.066s
ok  	golang.org/x/tools/cmd/fiximports	10.784s
ok  	golang.org/x/tools/cmd/go-contrib-init	0.014s
ok  	golang.org/x/tools/cmd/godoc	5.029s
ok  	golang.org/x/tools/cmd/gonew	0.041s
ok  	golang.org/x/tools/cmd/gorename	9.341s
ok  	golang.org/x/tools/cmd/signature-fuzzer/fuzz-driver	1.211s
ok  	golang.org/x/tools/cmd/signature-fuzzer/fuzz-runner	23.717s
ok  	golang.org/x/tools/cmd/signature-fuzzer/internal/fuzz-generator	4.880s
ok  	golang.org/x/tools/cmd/splitdwarf/internal/macho	0.006s
ok  	golang.org/x/tools/cmd/stringer	12.038s
ok  	golang.org/x/tools/container/intsets	0.499s
ok  	golang.org/x/tools/copyright	1.581s
ok  	golang.org/x/tools/cover	0.018s
ok  	golang.org/x/tools/go/analysis	0.067s
ok  	golang.org/x/tools/go/analysis/analysistest	1.469s
ok  	golang.org/x/tools/go/analysis/internal/analysisflags	0.134s
ok  	golang.org/x/tools/go/analysis/internal/checker	8.035s
ok  	golang.org/x/tools/go/analysis/internal/versiontest	2.391s
ok  	golang.org/x/tools/go/analysis/multichecker	10.081s
ok  	golang.org/x/tools/go/analysis/passes/appends	0.453s
ok  	golang.org/x/tools/go/analysis/passes/asmdecl	4.749s
ok  	golang.org/x/tools/go/analysis/passes/assign	1.186s
ok  	golang.org/x/tools/go/analysis/passes/atomic	0.458s
ok  	golang.org/x/tools/go/analysis/passes/atomicalign	0.468s
ok  	golang.org/x/tools/go/analysis/passes/bools	1.304s
ok  	golang.org/x/tools/go/analysis/passes/buildssa	0.427s
ok  	golang.org/x/tools/go/analysis/passes/buildtag	0.461s
ok  	golang.org/x/tools/go/analysis/passes/cgocall	11.295s
ok  	golang.org/x/tools/go/analysis/passes/composite	1.145s
ok  	golang.org/x/tools/go/analysis/passes/copylock	0.764s
ok  	golang.org/x/tools/go/analysis/passes/ctrlflow	1.167s
ok  	golang.org/x/tools/go/analysis/passes/deepequalerrors	1.020s
ok  	golang.org/x/tools/go/analysis/passes/defers	1.032s
ok  	golang.org/x/tools/go/analysis/passes/directive	1.212s
ok  	golang.org/x/tools/go/analysis/passes/errorsas	0.704s
ok  	golang.org/x/tools/go/analysis/passes/fieldalignment	0.239s
ok  	golang.org/x/tools/go/analysis/passes/findcall	0.534s
ok  	golang.org/x/tools/go/analysis/passes/framepointer	0.275s
ok  	golang.org/x/tools/go/analysis/passes/httpmux	1.731s
ok  	golang.org/x/tools/go/analysis/passes/httpresponse	1.632s
ok  	golang.org/x/tools/go/analysis/passes/ifaceassert	1.105s
ok  	golang.org/x/tools/go/analysis/passes/internal/analysisutil	0.027s
ok  	golang.org/x/tools/go/analysis/passes/loopclosure	1.205s
ok  	golang.org/x/tools/go/analysis/passes/lostcancel	1.736s
ok  	golang.org/x/tools/go/analysis/passes/nilfunc	0.644s
ok  	golang.org/x/tools/go/analysis/passes/nilness	0.256s
ok  	golang.org/x/tools/go/analysis/passes/pkgfact	0.226s
ok  	golang.org/x/tools/go/analysis/passes/printf	1.341s
ok  	golang.org/x/tools/go/analysis/passes/reflectvaluecompare	1.004s
ok  	golang.org/x/tools/go/analysis/passes/shadow	1.182s
ok  	golang.org/x/tools/go/analysis/passes/shift	0.269s
ok  	golang.org/x/tools/go/analysis/passes/sigchanyzer	1.177s
ok  	golang.org/x/tools/go/analysis/passes/slog	1.236s
ok  	golang.org/x/tools/go/analysis/passes/sortslice	0.842s
ok  	golang.org/x/tools/go/analysis/passes/stdmethods	2.607s
ok  	golang.org/x/tools/go/analysis/passes/stdversion	1.531s
ok  	golang.org/x/tools/go/analysis/passes/stringintconv	0.331s
ok  	golang.org/x/tools/go/analysis/passes/structtag	1.489s
ok  	golang.org/x/tools/go/analysis/passes/testinggoroutine	1.572s
ok  	golang.org/x/tools/go/analysis/passes/tests	1.708s
ok  	golang.org/x/tools/go/analysis/passes/timeformat	1.313s
ok  	golang.org/x/tools/go/analysis/passes/unmarshal	1.499s
ok  	golang.org/x/tools/go/analysis/passes/unreachable	0.301s
ok  	golang.org/x/tools/go/analysis/passes/unsafeptr	0.977s
ok  	golang.org/x/tools/go/analysis/passes/unusedresult	1.092s
ok  	golang.org/x/tools/go/analysis/passes/unusedwrite	0.358s
ok  	golang.org/x/tools/go/analysis/passes/usesgenerics	0.401s
--- FAIL: TestIntegration (3.03s)
--- FAIL: TestIntegration/GOPATH (1.62s)
    unitchecker_test.go:195: -json golang.org/fake/a: got <<# golang.org/fake/a
        {
        	"golang.org/fake/a": {
        		"findcall": [
        			{
        				"posn": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_GOPATH378214514/fake/src/golang.org/fake/a/a.go:4:11",
        				"end_posn": "-",
        				"message": "call of MyFunc123(...)",
        				"suggested_fixes": [
        					{
        						"message": "Add '_TEST_'",
        						"edits": [
        							{
        								"filename": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_GOPATH378214514/fake/src/golang.org/fake/a/a.go",
        								"start": 32,
        								"end": 32,
        								"new": "_TEST_"
        							}
        						]
        					}
        				]
        			}
        		]
        	}
        }
        >>, want match of regexp <<# golang.org/fake/a
        \{
        	"golang.org/fake/a": \{
        		"findcall": \[
        			\{
        				"posn": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?a/a.go:4:11",
        				"message": "call of MyFunc123\(...\)",
        				"suggested_fixes": \[
        					\{
        						"message": "Add '_TEST_'",
        						"edits": \[
        							\{
        								"filename": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?a/a.go",
        								"start": 32,
        								"end": 32,
        								"new": "_TEST_"
        							\}
        						\]
        					\}
        				\]
        			\}
        		\]
        	\}
        \}
        >>
    unitchecker_test.go:195: -json golang.org/fake/c: got <<# golang.org/fake/c
        {
        	"golang.org/fake/c": {
        		"assign": [
        			{
        				"posn": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_GOPATH378214514/fake/src/golang.org/fake/c/c.go:5:5",
        				"end_posn": "-",
        				"message": "self-assignment of i to i",
        				"suggested_fixes": [
        					{
        						"message": "Remove",
        						"edits": [
        							{
        								"filename": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_GOPATH378214514/fake/src/golang.org/fake/c/c.go",
        								"start": 37,
        								"end": 42,
        								"new": ""
        							}
        						]
        					}
        				]
        			}
        		]
        	}
        }
        >>, want match of regexp <<# golang.org/fake/c
        \{
        	"golang.org/fake/c": \{
        		"assign": \[
        			\{
        				"posn": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?c/c.go:5:5",
        				"message": "self-assignment of i to i",
        				"suggested_fixes": \[
        					\{
        						"message": "Remove",
        						"edits": \[
        							\{
        								"filename": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?c/c.go",
        								"start": 37,
        								"end": 42,
        								"new": ""
        							\}
        						\]
        					\}
        				\]
        			\}
        		\]
        	\}
        \}
        >>
--- FAIL: TestIntegration/Modules (1.41s)
    unitchecker_test.go:195: -json golang.org/fake/a: got <<# golang.org/fake/a
        {
        	"golang.org/fake/a": {
        		"findcall": [
        			{
        				"posn": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_Modules3250204485/fake/a/a.go:4:11",
        				"end_posn": "-",
        				"message": "call of MyFunc123(...)",
        				"suggested_fixes": [
        					{
        						"message": "Add '_TEST_'",
        						"edits": [
        							{
        								"filename": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_Modules3250204485/fake/a/a.go",
        								"start": 32,
        								"end": 32,
        								"new": "_TEST_"
        							}
        						]
        					}
        				]
        			}
        		]
        	}
        }
        >>, want match of regexp <<# golang.org/fake/a
        \{
        	"golang.org/fake/a": \{
        		"findcall": \[
        			\{
        				"posn": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?a/a.go:4:11",
        				"message": "call of MyFunc123\(...\)",
        				"suggested_fixes": \[
        					\{
        						"message": "Add '_TEST_'",
        						"edits": \[
        							\{
        								"filename": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?a/a.go",
        								"start": 32,
        								"end": 32,
        								"new": "_TEST_"
        							\}
        						\]
        					\}
        				\]
        			\}
        		\]
        	\}
        \}
        >>
    unitchecker_test.go:195: -json golang.org/fake/c: got <<# golang.org/fake/c
        {
        	"golang.org/fake/c": {
        		"assign": [
        			{
        				"posn": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_Modules3250204485/fake/c/c.go:5:5",
        				"end_posn": "-",
        				"message": "self-assignment of i to i",
        				"suggested_fixes": [
        					{
        						"message": "Remove",
        						"edits": [
        							{
        								"filename": "/home/swarming/.swarming/w/ir/x/t/TestIntegration_Modules3250204485/fake/c/c.go",
        								"start": 37,
        								"end": 42,
        								"new": ""
        							}
        						]
        					}
        				]
        			}
        		]
        	}
        }
        >>, want match of regexp <<# golang.org/fake/c
        \{
        	"golang.org/fake/c": \{
        		"assign": \[
        			\{
        				"posn": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?c/c.go:5:5",
        				"message": "self-assignment of i to i",
        				"suggested_fixes": \[
        					\{
        						"message": "Remove",
        						"edits": \[
        							\{
        								"filename": "([/._\-a-zA-Z0-9]+[\\/]fake[\\/])?c/c.go",
        								"start": 37,
        								"end": 42,
        								"new": ""
        							\}
        						\]
        					\}
        				\]
        			\}
        		\]
        	\}
        \}
        >>
FAIL
FAIL	golang.org/x/tools/go/analysis/unitchecker	5.525s
ok  	golang.org/x/tools/go/ast/astutil	0.032s
ok  	golang.org/x/tools/go/ast/inspector	0.109s
ok  	golang.org/x/tools/go/buildutil	1.042s
ok  	golang.org/x/tools/go/callgraph	0.005s [no tests to run]
ok  	golang.org/x/tools/go/callgraph/cha	0.011s
ok  	golang.org/x/tools/go/callgraph/rta	0.913s
ok  	golang.org/x/tools/go/callgraph/static	0.010s
ok  	golang.org/x/tools/go/callgraph/vta	0.367s
ok  	golang.org/x/tools/go/callgraph/vta/internal/trie	0.677s
ok  	golang.org/x/tools/go/cfg	0.012s
ok  	golang.org/x/tools/go/expect	0.028s
ok  	golang.org/x/tools/go/gccgoexportdata	0.004s
ok  	golang.org/x/tools/go/gcexportdata	0.560s
ok  	golang.org/x/tools/go/internal/gccgoimporter	0.025s
ok  	golang.org/x/tools/go/loader	4.064s
ok  	golang.org/x/tools/go/packages	16.136s
ok  	golang.org/x/tools/go/packages/packagestest	0.445s
ok  	golang.org/x/tools/go/ssa	4.104s
ok  	golang.org/x/tools/go/ssa/interp	1.913s
ok  	golang.org/x/tools/go/ssa/ssautil	1.472s
ok  	golang.org/x/tools/go/types/objectpath	0.021s
ok  	golang.org/x/tools/go/types/typeutil	0.014s
ok  	golang.org/x/tools/godoc	0.631s
ok  	golang.org/x/tools/godoc/redirect	0.014s
ok  	golang.org/x/tools/godoc/static	1.050s
ok  	golang.org/x/tools/godoc/vfs	0.014s
ok  	golang.org/x/tools/godoc/vfs/gatefs	0.022s
ok  	golang.org/x/tools/godoc/vfs/mapfs	0.039s
ok  	golang.org/x/tools/godoc/vfs/zipfs	0.023s
ok  	golang.org/x/tools/internal/aliases	0.085s
ok  	golang.org/x/tools/internal/analysisinternal	0.220s
ok  	golang.org/x/tools/internal/apidiff	1.839s
ok  	golang.org/x/tools/internal/bisect	0.069s
ok  	golang.org/x/tools/internal/diff	0.153s
ok  	golang.org/x/tools/internal/diff/difftest	0.389s
ok  	golang.org/x/tools/internal/diff/lcs	0.186s
ok  	golang.org/x/tools/internal/diff/myers	0.045s
ok  	golang.org/x/tools/internal/diffp	0.060s
ok  	golang.org/x/tools/internal/drivertest	2.574s
ok  	golang.org/x/tools/internal/edit	0.022s
ok  	golang.org/x/tools/internal/event	0.040s [no tests to run]
ok  	golang.org/x/tools/internal/event/export	0.085s
ok  	golang.org/x/tools/internal/event/export/ocagent	0.037s
ok  	golang.org/x/tools/internal/event/export/ocagent/wire	0.072s
ok  	golang.org/x/tools/internal/event/keys	0.034s
ok  	golang.org/x/tools/internal/event/label	0.026s
ok  	golang.org/x/tools/internal/facts	1.449s
ok  	golang.org/x/tools/internal/gcimporter	11.270s
ok  	golang.org/x/tools/internal/gocommand	0.558s
ok  	golang.org/x/tools/internal/gopathwalk	0.027s
ok  	golang.org/x/tools/internal/imports	10.721s
ok  	golang.org/x/tools/internal/jsonrpc2	0.196s
ok  	golang.org/x/tools/internal/jsonrpc2/servertest	0.030s
ok  	golang.org/x/tools/internal/jsonrpc2_v2	0.103s
ok  	golang.org/x/tools/internal/memoize	0.008s
ok  	golang.org/x/tools/internal/pprof	0.049s
ok  	golang.org/x/tools/internal/proxydir	0.051s
ok  	golang.org/x/tools/internal/refactor/inline	2.242s
ok  	golang.org/x/tools/internal/refactor/inline/analyzer	0.240s
ok  	golang.org/x/tools/internal/robustio	2.013s
ok  	golang.org/x/tools/internal/stack	0.007s
ok  	golang.org/x/tools/internal/testfiles	0.218s
ok  	golang.org/x/tools/internal/tokeninternal	0.025s
ok  	golang.org/x/tools/internal/typeparams	0.020s
ok  	golang.org/x/tools/internal/typesinternal	0.004s
ok  	golang.org/x/tools/internal/versions	0.017s
ok  	golang.org/x/tools/playground/socket	0.034s
ok  	golang.org/x/tools/present	0.040s
ok  	golang.org/x/tools/refactor/eg	1.596s
ok  	golang.org/x/tools/refactor/importgraph	1.318s
ok  	golang.org/x/tools/refactor/rename	0.537s
ok  	golang.org/x/tools/refactor/satisfy	0.070s
ok  	golang.org/x/tools/txtar	0.005s
Warning: no '=' in invocation-link-artifacts pair: "", ignoring
[I2024-07-26T18:26:59.303074Z 524424 0 cmd_stream.go:488] rdb-stream: the test process terminated
[I2024-07-26T18:26:59.303202Z 524424 0 sink.go:371] SinkServer: shutdown started
[I2024-07-26T18:26:59.303271Z 524424 0 sink.go:349] SinkServer: HTTP server stopped with "http: Server closed"
[I2024-07-26T18:26:59.303293Z 524424 0 sink_server.go:96] SinkServer: draining TestResult channel started
[I2024-07-26T18:26:59.896017Z 524424 0 sink_server.go:98] SinkServer: draining TestResult channel ended
[I2024-07-26T18:26:59.896062Z 524424 0 sink_server.go:100] SinkServer: draining Artifact channel started
[I2024-07-26T18:27:00.083676Z 524424 0 sink_server.go:102] SinkServer: draining Artifact channel ended
[I2024-07-26T18:27:00.083721Z 524424 0 sink.go:374] SinkServer: shutdown completed successfully
[I2024-07-26T18:27:00.083741Z 524424 0 cmd_stream.go:420] rdb-stream: exiting with 1