linux-amd64-race at cb4cd9e17753b5cd8ee4cd5b1f23d46241b485f1 building tools at fd00574339946b3af24d788d87d8d3a57cd0390f

testing in module mode; GOMOD=/workdir/gopath/src/golang.org/x/tools/go.mod

:: Running /workdir/go/bin/go with args ["/workdir/go/bin/go" "test" "-short" "-race" "golang.org/x/tools/..."] and env ["PATH=/workdir/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin" "HOSTNAME=buildlet-linux-jessie-rn335cfd8" "DEBIAN_FRONTEND=noninteractive" "HOME=/root" "USER=root" "GO_STAGE0_NET_DELAY=100ms" "GO_STAGE0_DL_DELAY=100ms" "WORKDIR=/workdir" "GOROOT_BOOTSTRAP=/workdir/go1.4" "GO_BUILDER_NAME=linux-amd64-race" "GOROOT_BOOTSTRAP=/go1.4" "GO_DISABLE_OUTBOUND_NETWORK=1" "GOROOT=/workdir/go" "GOPATH=/workdir/gopath" "GOPROXY=http://10.240.0.39:30157" "TMPDIR=/workdir/tmp" "GOCACHE=/workdir/gocache"] in dir /workdir/gopath/src/golang.org/x/tools

go: downloading golang.org/x/sys v0.0.0-20210510120138-977fb7262007
go: downloading golang.org/x/xerrors v0.0.0-20200804184101-5ec99f83aff1
go: downloading golang.org/x/net v0.0.0-20210405180319-a5a99cb37ef4
go: downloading golang.org/x/mod v0.4.2
go: downloading github.com/yuin/goldmark v1.3.5
go: downloading golang.org/x/sync v0.0.0-20210220032951-036812b2e83c
ok  	golang.org/x/tools/benchmark/parse	0.038s
ok  	golang.org/x/tools/blog	0.080s
?   	golang.org/x/tools/blog/atom	[no test files]
?   	golang.org/x/tools/cmd/auth/authtest	[no test files]
?   	golang.org/x/tools/cmd/auth/cookieauth	[no test files]
?   	golang.org/x/tools/cmd/auth/gitauth	[no test files]
?   	golang.org/x/tools/cmd/auth/netrcauth	[no test files]
ok  	golang.org/x/tools/cmd/benchcmp	0.031s
ok  	golang.org/x/tools/cmd/bundle	0.942s
ok  	golang.org/x/tools/cmd/callgraph	35.514s
?   	golang.org/x/tools/cmd/compilebench	[no test files]
ok  	golang.org/x/tools/cmd/cover	1.530s
ok  	golang.org/x/tools/cmd/digraph	0.041s
?   	golang.org/x/tools/cmd/eg	[no test files]
ok  	golang.org/x/tools/cmd/fiximports	12.146s
ok  	golang.org/x/tools/cmd/getgo	0.021s
?   	golang.org/x/tools/cmd/getgo/server	[no test files]
ok  	golang.org/x/tools/cmd/go-contrib-init	0.050s
?   	golang.org/x/tools/cmd/godex	[no test files]
ok  	golang.org/x/tools/cmd/godoc	27.309s
?   	golang.org/x/tools/cmd/goimports	[no test files]
?   	golang.org/x/tools/cmd/gomvpkg	[no test files]
ok  	golang.org/x/tools/cmd/gorename	10.979s
?   	golang.org/x/tools/cmd/gotype	[no test files]
?   	golang.org/x/tools/cmd/goyacc	[no test files]
ok  	golang.org/x/tools/cmd/guru	0.114s
?   	golang.org/x/tools/cmd/guru/serial	[no test files]
?   	golang.org/x/tools/cmd/html2article	[no test files]
?   	golang.org/x/tools/cmd/present	[no test files]
?   	golang.org/x/tools/cmd/present2md	[no test files]
?   	golang.org/x/tools/cmd/splitdwarf	[no test files]
ok  	golang.org/x/tools/cmd/splitdwarf/internal/macho	0.070s
?   	golang.org/x/tools/cmd/ssadump	[no test files]
?   	golang.org/x/tools/cmd/stress	[no test files]
ok  	golang.org/x/tools/cmd/stringer	14.052s
?   	golang.org/x/tools/cmd/toolstash	[no test files]
ok  	golang.org/x/tools/container/intsets	2.187s
ok  	golang.org/x/tools/copyright	10.323s
ok  	golang.org/x/tools/cover	0.045s
ok  	golang.org/x/tools/go/analysis	0.032s
ok  	golang.org/x/tools/go/analysis/analysistest	10.922s
ok  	golang.org/x/tools/go/analysis/internal/analysisflags	0.307s
ok  	golang.org/x/tools/go/analysis/internal/checker	0.156s
ok  	golang.org/x/tools/go/analysis/internal/facts	0.454s
ok  	golang.org/x/tools/go/analysis/multichecker	84.710s
ok  	golang.org/x/tools/go/analysis/passes/asmdecl	2.513s
ok  	golang.org/x/tools/go/analysis/passes/assign	5.268s
ok  	golang.org/x/tools/go/analysis/passes/atomic	0.239s
ok  	golang.org/x/tools/go/analysis/passes/atomicalign	0.138s
ok  	golang.org/x/tools/go/analysis/passes/bools	8.793s
ok  	golang.org/x/tools/go/analysis/passes/buildssa	0.188s
ok  	golang.org/x/tools/go/analysis/passes/buildtag	0.107s
ok  	golang.org/x/tools/go/analysis/passes/cgocall	12.772s
ok  	golang.org/x/tools/go/analysis/passes/composite	13.533s
ok  	golang.org/x/tools/go/analysis/passes/copylock	12.284s
ok  	golang.org/x/tools/go/analysis/passes/ctrlflow	17.143s
ok  	golang.org/x/tools/go/analysis/passes/deepequalerrors	12.093s
ok  	golang.org/x/tools/go/analysis/passes/errorsas	10.615s
ok  	golang.org/x/tools/go/analysis/passes/fieldalignment	0.216s
?   	golang.org/x/tools/go/analysis/passes/fieldalignment/cmd/fieldalignment	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/findcall	0.450s
?   	golang.org/x/tools/go/analysis/passes/findcall/cmd/findcall	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/framepointer	0.112s
ok  	golang.org/x/tools/go/analysis/passes/httpresponse	19.722s
ok  	golang.org/x/tools/go/analysis/passes/ifaceassert	13.104s
?   	golang.org/x/tools/go/analysis/passes/ifaceassert/cmd/ifaceassert	[no test files]
?   	golang.org/x/tools/go/analysis/passes/inspect	[no test files]
?   	golang.org/x/tools/go/analysis/passes/internal/analysisutil	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/loopclosure	0.107s
ok  	golang.org/x/tools/go/analysis/passes/lostcancel	15.540s
?   	golang.org/x/tools/go/analysis/passes/lostcancel/cmd/lostcancel	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/nilfunc	0.272s
ok  	golang.org/x/tools/go/analysis/passes/nilness	0.187s
?   	golang.org/x/tools/go/analysis/passes/nilness/cmd/nilness	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/pkgfact	0.184s
ok  	golang.org/x/tools/go/analysis/passes/printf	12.961s
ok  	golang.org/x/tools/go/analysis/passes/reflectvaluecompare	8.016s
ok  	golang.org/x/tools/go/analysis/passes/shadow	14.757s
?   	golang.org/x/tools/go/analysis/passes/shadow/cmd/shadow	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/shift	0.220s
ok  	golang.org/x/tools/go/analysis/passes/sigchanyzer	15.287s
ok  	golang.org/x/tools/go/analysis/passes/sortslice	12.931s
ok  	golang.org/x/tools/go/analysis/passes/stdmethods	22.057s
ok  	golang.org/x/tools/go/analysis/passes/stringintconv	0.092s
?   	golang.org/x/tools/go/analysis/passes/stringintconv/cmd/stringintconv	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/structtag	10.181s
ok  	golang.org/x/tools/go/analysis/passes/testinggoroutine	11.351s
ok  	golang.org/x/tools/go/analysis/passes/tests	14.146s
ok  	golang.org/x/tools/go/analysis/passes/unmarshal	13.082s
?   	golang.org/x/tools/go/analysis/passes/unmarshal/cmd/unmarshal	[no test files]
ok  	golang.org/x/tools/go/analysis/passes/unreachable	0.498s
ok  	golang.org/x/tools/go/analysis/passes/unsafeptr	13.543s
ok  	golang.org/x/tools/go/analysis/passes/unusedresult	11.374s
ok  	golang.org/x/tools/go/analysis/passes/unusedwrite	0.233s
?   	golang.org/x/tools/go/analysis/singlechecker	[no test files]
ok  	golang.org/x/tools/go/analysis/unitchecker	4.002s
ok  	golang.org/x/tools/go/ast/astutil	0.277s
ok  	golang.org/x/tools/go/ast/inspector	1.029s
ok  	golang.org/x/tools/go/buildutil	1.090s
?   	golang.org/x/tools/go/callgraph	[no test files]
ok  	golang.org/x/tools/go/callgraph/cha	0.073s
ok  	golang.org/x/tools/go/callgraph/rta	0.075s
ok  	golang.org/x/tools/go/callgraph/static	0.051s
ok  	golang.org/x/tools/go/callgraph/vta	0.138s
ok  	golang.org/x/tools/go/cfg	0.040s
ok  	golang.org/x/tools/go/expect	0.039s
ok  	golang.org/x/tools/go/gccgoexportdata	0.036s
ok  	golang.org/x/tools/go/gcexportdata	0.388s
?   	golang.org/x/tools/go/internal/cgo	[no test files]
ok  	golang.org/x/tools/go/internal/gccgoimporter	0.102s
ok  	golang.org/x/tools/go/internal/gcimporter	35.082s
?   	golang.org/x/tools/go/internal/packagesdriver	[no test files]
ok  	golang.org/x/tools/go/loader	58.977s
ok  	golang.org/x/tools/go/packages	393.160s
?   	golang.org/x/tools/go/packages/gopackages	[no test files]
ok  	golang.org/x/tools/go/packages/packagestest	0.715s
ok  	golang.org/x/tools/go/pointer	36.176s
ok  	golang.org/x/tools/go/ssa	53.141s
ok  	golang.org/x/tools/go/ssa/interp	2.095s
ok  	golang.org/x/tools/go/ssa/ssautil	8.666s
ok  	golang.org/x/tools/go/types/objectpath	0.064s
ok  	golang.org/x/tools/go/types/typeutil	6.501s
ok  	golang.org/x/tools/go/vcs	0.052s
ok  	golang.org/x/tools/godoc	4.519s
?   	golang.org/x/tools/godoc/analysis	[no test files]
ok  	golang.org/x/tools/godoc/redirect	5.357s
ok  	golang.org/x/tools/godoc/static	33.335s
?   	golang.org/x/tools/godoc/util	[no test files]
ok  	golang.org/x/tools/godoc/vfs	0.076s
ok  	golang.org/x/tools/godoc/vfs/gatefs	0.055s
?   	golang.org/x/tools/godoc/vfs/httpfs	[no test files]
ok  	golang.org/x/tools/godoc/vfs/mapfs	0.041s
ok  	golang.org/x/tools/godoc/vfs/zipfs	0.062s
?   	golang.org/x/tools/imports	[no test files]
?   	golang.org/x/tools/internal/analysisinternal	[no test files]
ok  	golang.org/x/tools/internal/apidiff	1.380s
ok  	golang.org/x/tools/internal/event	0.055s [no tests to run]
?   	golang.org/x/tools/internal/event/core	[no test files]
ok  	golang.org/x/tools/internal/event/export	0.056s
?   	golang.org/x/tools/internal/event/export/eventtest	[no test files]
?   	golang.org/x/tools/internal/event/export/metric	[no test files]
ok  	golang.org/x/tools/internal/event/export/ocagent	0.083s
ok  	golang.org/x/tools/internal/event/export/ocagent/wire	0.038s
?   	golang.org/x/tools/internal/event/export/prometheus	[no test files]
?   	golang.org/x/tools/internal/event/keys	[no test files]
ok  	golang.org/x/tools/internal/event/label	0.072s
?   	golang.org/x/tools/internal/fakenet	[no test files]
ok  	golang.org/x/tools/internal/fastwalk	0.066s
ok  	golang.org/x/tools/internal/gocommand	0.087s
ok  	golang.org/x/tools/internal/gopathwalk	0.039s
ok  	golang.org/x/tools/internal/imports	49.821s
ok  	golang.org/x/tools/internal/jsonrpc2	0.224s
ok  	golang.org/x/tools/internal/jsonrpc2/servertest	0.052s
ok  	golang.org/x/tools/internal/jsonrpc2_v2	0.402s
ok  	golang.org/x/tools/internal/lsp	120.138s
ok  	golang.org/x/tools/internal/lsp/analysis/fillreturns	19.310s
ok  	golang.org/x/tools/internal/lsp/analysis/fillstruct	13.206s
ok  	golang.org/x/tools/internal/lsp/analysis/nonewvars	12.494s
ok  	golang.org/x/tools/internal/lsp/analysis/noresultvalues	0.145s
ok  	golang.org/x/tools/internal/lsp/analysis/simplifycompositelit	0.375s
ok  	golang.org/x/tools/internal/lsp/analysis/simplifyrange	12.848s
ok  	golang.org/x/tools/internal/lsp/analysis/simplifyslice	0.161s
ok  	golang.org/x/tools/internal/lsp/analysis/undeclaredname	0.261s
ok  	golang.org/x/tools/internal/lsp/analysis/unusedparams	19.099s
?   	golang.org/x/tools/internal/lsp/browser	[no test files]
ok  	golang.org/x/tools/internal/lsp/cache	0.105s
ok  	golang.org/x/tools/internal/lsp/cmd	107.901s
?   	golang.org/x/tools/internal/lsp/cmd/test	[no test files]
ok  	golang.org/x/tools/internal/lsp/command	17.298s
?   	golang.org/x/tools/internal/lsp/command/commandmeta	[no test files]
?   	golang.org/x/tools/internal/lsp/command/gen	[no test files]
?   	golang.org/x/tools/internal/lsp/debug	[no test files]
?   	golang.org/x/tools/internal/lsp/debug/log	[no test files]
?   	golang.org/x/tools/internal/lsp/debug/tag	[no test files]
ok  	golang.org/x/tools/internal/lsp/diff	0.070s
ok  	golang.org/x/tools/internal/lsp/diff/difftest	0.280s
ok  	golang.org/x/tools/internal/lsp/diff/myers	0.033s
ok  	golang.org/x/tools/internal/lsp/fake	0.067s
ok  	golang.org/x/tools/internal/lsp/fuzzy	0.156s
?   	golang.org/x/tools/internal/lsp/helper	[no test files]
ok  	golang.org/x/tools/internal/lsp/lsprpc	4.361s
ok  	golang.org/x/tools/internal/lsp/mod	0.231s
ok  	golang.org/x/tools/internal/lsp/progress	0.047s
?   	golang.org/x/tools/internal/lsp/protocol	[no test files]
ok  	golang.org/x/tools/internal/lsp/regtest	0.132s
ok  	golang.org/x/tools/internal/lsp/snippet	0.047s
ok  	golang.org/x/tools/internal/lsp/source	86.841s
ok  	golang.org/x/tools/internal/lsp/source/completion	0.109s
ok  	golang.org/x/tools/internal/lsp/template	0.142s
?   	golang.org/x/tools/internal/lsp/tests	[no test files]
ok  	golang.org/x/tools/internal/memoize	0.045s
?   	golang.org/x/tools/internal/mod/lazyregexp	[no test files]
?   	golang.org/x/tools/internal/mod/modfile	[no test files]
?   	golang.org/x/tools/internal/packagesinternal	[no test files]
ok  	golang.org/x/tools/internal/proxydir	0.190s
ok  	golang.org/x/tools/internal/span	0.066s
ok  	golang.org/x/tools/internal/stack	0.062s
?   	golang.org/x/tools/internal/stack/gostacks	[no test files]
?   	golang.org/x/tools/internal/stack/stacktest	[no test files]
?   	golang.org/x/tools/internal/testenv	[no test files]
?   	golang.org/x/tools/internal/tool	[no test files]
?   	golang.org/x/tools/internal/typeparams	[no test files]
?   	golang.org/x/tools/internal/typesinternal	[no test files]
?   	golang.org/x/tools/internal/xcontext	[no test files]
?   	golang.org/x/tools/playground	[no test files]
ok  	golang.org/x/tools/playground/socket	0.042s
ok  	golang.org/x/tools/present	0.171s
ok  	golang.org/x/tools/refactor/eg	19.759s
ok  	golang.org/x/tools/refactor/importgraph	6.087s
ok  	golang.org/x/tools/refactor/rename	0.640s
?   	golang.org/x/tools/refactor/satisfy	[no test files]
ok  	golang.org/x/tools/txtar	0.039s
:: Running /workdir/go/bin/go with args ["/workdir/go/bin/go" "test" "-short" "-race" "golang.org/x/tools/gopls/..."] and env ["PATH=/workdir/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin" "HOSTNAME=buildlet-linux-jessie-rn335cfd8" "DEBIAN_FRONTEND=noninteractive" "HOME=/root" "USER=root" "GO_STAGE0_NET_DELAY=100ms" "GO_STAGE0_DL_DELAY=100ms" "WORKDIR=/workdir" "GOROOT_BOOTSTRAP=/workdir/go1.4" "GO_BUILDER_NAME=linux-amd64-race" "GOROOT_BOOTSTRAP=/go1.4" "GO_DISABLE_OUTBOUND_NETWORK=1" "GOROOT=/workdir/go" "GOPATH=/workdir/gopath" "GOPROXY=http://10.240.0.39:30157" "TMPDIR=/workdir/tmp" "GOCACHE=/workdir/gocache"] in dir /workdir/gopath/src/golang.org/x/tools/gopls

go: downloading github.com/sergi/go-diff v1.1.0
go: downloading honnef.co/go/tools v0.2.0
go: downloading mvdan.cc/gofumpt v0.1.1
go: downloading mvdan.cc/xurls/v2 v2.2.0
go: downloading github.com/sanity-io/litter v1.5.0
go: downloading github.com/jba/templatecheck v0.6.0
go: downloading github.com/google/go-cmp v0.5.5
go: downloading github.com/google/safehtml v0.0.2
go: downloading github.com/BurntSushi/toml v0.3.1
go: downloading golang.org/x/text v0.3.6
?   	golang.org/x/tools/gopls	[no test files]
ok  	golang.org/x/tools/gopls/doc	33.349s
?   	golang.org/x/tools/gopls/integration/govim	[no test files]
ok  	golang.org/x/tools/gopls/internal/hooks	1.143s
ok  	golang.org/x/tools/gopls/internal/regtest/bench	0.585s
ok  	golang.org/x/tools/gopls/internal/regtest/codelens	47.517s
ok  	golang.org/x/tools/gopls/internal/regtest/completion	34.638s
#### Start Gopls Test Logs for "TestProgressBarErrors/experimental"
[Trace - 20:21:31.270 PM] Sending request 'initialize - (1)'.
Params: {"processId":0,"clientInfo":{"name":"fakeclient","version":"v1.0.0"},"rootUri":"","capabilities":{"workspace":{"didChangeConfiguration":{},"didChangeWatchedFiles":{"dynamicRegistration":true},"executeCommand":{},"semanticTokens":{},"codeLens":{},"configuration":true},"textDocument":{"synchronization":{},"completion":{"completionItem":{"snippetSupport":true,"tagSupport":{"valueSet":[1]},"resolveSupport":{"properties":null},"insertTextModeSupport":{"valueSet":null}},"completionItemKind":{}},"hover":{},"signatureHelp":{"signatureInformation":{"parameterInformation":{}}},"declaration":{},"definition":{},"typeDefinition":{},"implementation":{},"references":{},"documentHighlight":{},"documentSymbol":{"symbolKind":{},"tagSupport":{"valueSet":null}},"codeAction":{"codeActionLiteralSupport":{"codeActionKind":{"valueSet":null}},"resolveSupport":{"properties":null}},"codeLens":{},"documentLink":{},"colorProvider":{},"formatting":{},"rangeFormatting":{},"onTypeFormatting":{},"rename":{},"foldingRange":{},"selectionRange":{},"publishDiagnostics":{"tagSupport":{"valueSet":null}},"callHierarchy":{},"semanticTokens":{"requests":{},"tokenTypes":null,"tokenModifiers":null,"formats":null},"linkedEditingRange":{},"moniker":{}},"window":{"workDoneProgress":true,"showMessage":{"messageActionItem":{}},"showDocument":{"support":false}},"general":{"staleRequestSupport":{"cancel":false,"retryOnContentModified":null},"regularExpressions":{"engine":""},"markdown":{"parser":""}}},"initializationOptions":{"completionBudget":"10s","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/gopath","GOPROXY":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/proxy","GOSUMDB":"off"},"expandWorkspaceToModule":true,"experimentalDiagnosticsDelay":"10ms","verboseWorkDoneProgress":true},"trace":"messages","workspaceFolders":[{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work","name":"work"}]}


[Trace - 20:21:31.273 PM] Received response 'initialize - (1)' in 2ms.
Result: {"capabilities":{"textDocumentSync":{"openClose":true,"change":2,"save":{}},"completionProvider":{"triggerCharacters":["."],"completionItem":{}},"hoverProvider":true,"signatureHelpProvider":{"triggerCharacters":["(",","]},"definitionProvider":true,"typeDefinitionProvider":true,"implementationProvider":true,"referencesProvider":true,"documentHighlightProvider":true,"documentSymbolProvider":true,"codeActionProvider":true,"codeLensProvider":{},"documentLinkProvider":{},"workspaceSymbolProvider":true,"documentFormattingProvider":true,"documentOnTypeFormattingProvider":{"firstTriggerCharacter":""},"renameProvider":true,"foldingRangeProvider":true,"executeCommandProvider":{"commands":["gopls.add_dependency","gopls.add_import","gopls.apply_fix","gopls.check_upgrades","gopls.gc_details","gopls.generate","gopls.generate_gopls_mod","gopls.go_get_package","gopls.list_known_packages","gopls.regenerate_cgo","gopls.remove_dependency","gopls.run_tests","gopls.start_debugging","gopls.test","gopls.tidy","gopls.toggle_gc_details","gopls.update_go_sum","gopls.upgrade_dependency","gopls.vendor","gopls.workspace_metadata"]},"callHierarchyProvider":true,"workspace":{"workspaceFolders":{"supported":true,"changeNotifications":"workspace/didChangeWorkspaceFolders"}}},"serverInfo":{"name":"gopls","version":"{\"path\":\"gopls, built in GOPATH mode\",\"version\":\"master\"}"}}


[Trace - 20:21:31.275 PM] Sending notification 'initialized'.
Params: {}


[Trace - 20:21:31.277 PM] Received request 'window/workDoneProgress/create - (1)'.
Params: {"token":"5508906111153315027"}


[Trace - 20:21:31.278 PM] Sending response 'window/workDoneProgress/create - (1)' in 1ms.
Result: 


[Trace - 20:21:31.279 PM] Received notification '$/progress'.
Params: {"token":"5508906111153315027","value":{"kind":"begin","title":"diagnosing initial workspace load","message":"Calculating diagnostics for initial workspace load..."}}


[Trace - 20:21:31.280 PM] Received request 'window/workDoneProgress/create - (2)'.
Params: {"token":"676017818015408657"}


[Trace - 20:21:31.282 PM] Sending response 'window/workDoneProgress/create - (2)' in 1ms.
Result: 


[Trace - 20:21:31.283 PM] Received notification '$/progress'.
Params: {"token":"676017818015408657","value":{"kind":"begin","title":"Setting up workspace","message":"Loading packages..."}}


[Trace - 20:21:31.285 PM] Received request 'workspace/configuration - (3)'.
Params: {"items":[{"scopeUri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work","section":"gopls"}]}


[Trace - 20:21:31.287 PM] Sending response 'workspace/configuration - (3)' in 2ms.
Result: [{"completionBudget":"10s","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/gopath","GOPROXY":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/proxy","GOSUMDB":"off"},"expandWorkspaceToModule":true,"experimentalDiagnosticsDelay":"10ms","verboseWorkDoneProgress":true}]


[Trace - 20:21:31.303 PM] Received notification '$/progress'.
Params: {"token":"676017818015408657","value":{"kind":"end","message":"Finished loading packages."}}


[Trace - 20:21:31.304 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 copying workspace dir: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul\n"}


[Trace - 20:21:31.307 PM] Received request 'client/registerCapability - (4)'.
Params: {"registrations":[{"id":"workspace/didChangeWatchedFiles-0","method":"workspace/didChangeWatchedFiles","registerOptions":{"watchers":[{"globPattern":"**/*.{go,mod,sum}","kind":7},{"globPattern":"**/*.*tmpl","kind":7}]}}]}


[Trace - 20:21:31.309 PM] Received request 'window/workDoneProgress/create - (5)'.
Params: {"token":"4973045475441444873"}


[Trace - 20:21:31.310 PM] Sending response 'client/registerCapability - (4)' in 2ms.
Result: 


[Trace - 20:21:31.310 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 errors loading workspace: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul\n\tsnapshot=0\n\tdirectory=file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work\n"}


[Trace - 20:21:31.312 PM] Received notification 'window/logMessage'.
Params: {"type":3,"message":"2021/07/07 20:21:31 go env for /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work\n(root /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work)\n(go version go version go1.16.5 linux/amd64)\n(valid build configuration = true)\n(build flags: [])\nGOINSECURE=\nGOFLAGS=\nGOPATH=/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/gopath\nGOMOD=/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod\nGOMODCACHE=\nGONOPROXY=\nGONOSUMDB=\nGOSUMDB=off\nGO111MODULE=\nGOPRIVATE=\nGOCACHE=/workdir/gocache\nGOPROXY=file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/proxy\nGOROOT=/workdir/go\n\n"}


[Trace - 20:21:31.312 PM] Sending response 'window/workDoneProgress/create - (5)' in 3ms.
Result: 


[Trace - 20:21:31.314 PM] Received notification '$/progress'.
Params: {"token":"4973045475441444873","value":{"kind":"begin","title":"Error loading workspace","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul"}}


[Trace - 20:21:31.316 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","diagnostics":[{"range":{"start":{"line":0,"character":0},"end":{"line":0,"character":0}},"severity":1,"source":"go list","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul"},{"range":{"start":{"line":0,"character":0},"end":{"line":0,"character":0}},"severity":1,"source":"syntax","message":"unknown directive: modul"}]}


[Trace - 20:21:31.318 PM] Received notification '$/progress'.
Params: {"token":"5508906111153315027","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.321 PM] Sending notification 'textDocument/didOpen'.
Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","languageId":"","version":1,"text":"modul mod.com\n\ngo 1.12\n"}}


[Trace - 20:21:31.322 PM] Sending notification 'textDocument/didChange'.
Params: {"textDocument":{"version":2,"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod"},"contentChanges":[{"range":{"start":{"line":0,"character":0},"end":{"line":3,"character":0}},"text":"module mod.com\n\ngo 1.hello\n"}]}


[Trace - 20:21:31.323 PM] Received request 'window/workDoneProgress/create - (6)'.
Params: {"token":"6330885697262780955"}


[Trace - 20:21:31.326 PM] Sending notification 'textDocument/willSave'.
Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod"},"reason":1}


[Trace - 20:21:31.327 PM] Sending response 'window/workDoneProgress/create - (6)' in 3ms.
Result: 


[Trace - 20:21:31.327 PM] Received notification '$/progress'.
Params: {"token":"6330885697262780955","value":{"kind":"begin","title":"diagnosing opened files","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.327 PM] Sending notification 'textDocument/didSave'.
Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod"}}


[Trace - 20:21:31.333 PM] Sending notification 'workspace/didChangeWatchedFiles'.
Params: {"changes":[{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","type":2}]}


[Trace - 20:21:31.333 PM] Received request 'window/workDoneProgress/create - (7)'.
Params: {"token":"2475840605395901410"}


[Trace - 20:21:31.336 PM] Sending response 'window/workDoneProgress/create - (7)' in 2ms.
Result: 


[Trace - 20:21:31.336 PM] Received notification '$/progress'.
Params: {"token":"2475840605395901410","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.339 PM] Received notification '$/progress'.
Params: {"token":"6330885697262780955","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.340 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 getting workspace dir: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"}


[Trace - 20:21:31.342 PM] Received request 'window/workDoneProgress/create - (8)'.
Params: {"token":"381114526762976423"}


[Trace - 20:21:31.343 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 copying workspace dir: opening snapshot go.mod: open go.mod: no such file or directory\n"}


[Trace - 20:21:31.345 PM] Sending response 'window/workDoneProgress/create - (8)' in 2ms.
Result: 


[Trace - 20:21:31.349 PM] Received notification '$/progress'.
Params: {"token":"381114526762976423","value":{"kind":"begin","title":"diagnosing saved files","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.352 PM] Received notification '$/progress'.
Params: {"token":"4973045475441444873","value":{"kind":"report","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"}}


[Trace - 20:21:31.354 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 errors loading workspace: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n\tsnapshot=2\n\tdirectory=file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work\n"}


[Trace - 20:21:31.358 PM] Received request 'window/workDoneProgress/create - (9)'.
Params: {"token":"5977161299719085799"}


[Trace - 20:21:31.359 PM] Sending notification 'textDocument/didChange'.
Params: {"textDocument":{"version":3,"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod"},"contentChanges":[{"range":{"start":{"line":2,"character":0},"end":{"line":2,"character":10}},"text":"go 1.12"}]}


[Trace - 20:21:31.359 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 getting workspace dir: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"}


[Trace - 20:21:31.362 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 copying workspace dir: opening snapshot go.mod: open go.mod: no such file or directory\n"}


[Trace - 20:21:31.363 PM] Sending response 'window/workDoneProgress/create - (9)' in 4ms.
Result: 


[Trace - 20:21:31.363 PM] Sending notification 'textDocument/willSave'.
Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod"},"reason":1}


[Trace - 20:21:31.365 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","version":2,"diagnostics":[{"range":{"start":{"line":0,"character":0},"end":{"line":0,"character":0}},"severity":1,"source":"go list","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"},{"range":{"start":{"line":2,"character":0},"end":{"line":2,"character":0}},"severity":1,"source":"syntax","message":"invalid go version '1.hello': must match format 1.23"}]}


[Trace - 20:21:31.366 PM] Received notification '$/progress'.
Params: {"token":"5977161299719085799","value":{"kind":"begin","title":"diagnosing files changed on disk","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.370 PM] Received notification '$/progress'.
Params: {"token":"2475840605395901410","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.370 PM] Sending notification 'textDocument/didSave'.
Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod"}}


[Trace - 20:21:31.375 PM] Sending notification 'workspace/didChangeWatchedFiles'.
Params: {"changes":[{"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","type":2}]}


[Trace - 20:21:31.375 PM] Received notification '$/progress'.
Params: {"token":"4973045475441444873","value":{"kind":"report","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"}}


[Trace - 20:21:31.377 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 errors loading workspace: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n\tsnapshot=3\n\tdirectory=file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work\n"}


[Trace - 20:21:31.379 PM] Received request 'window/workDoneProgress/create - (10)'.
Params: {"token":"3809697317681224415"}


[Trace - 20:21:31.381 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 getting workspace dir: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"}


[Trace - 20:21:31.382 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 copying workspace dir: opening snapshot go.mod: open go.mod: no such file or directory\n"}


[Trace - 20:21:31.383 PM] Sending response 'window/workDoneProgress/create - (10)' in 3ms.
Result: 


[Trace - 20:21:31.385 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","version":2,"diagnostics":[{"range":{"start":{"line":0,"character":0},"end":{"line":0,"character":0}},"severity":1,"source":"go list","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"},{"range":{"start":{"line":2,"character":0},"end":{"line":2,"character":0}},"severity":1,"source":"syntax","message":"invalid go version '1.hello': must match format 1.23"}]}


[Trace - 20:21:31.385 PM] Received notification '$/progress'.
Params: {"token":"3809697317681224415","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.388 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 errors loading workspace: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n\tsnapshot=4\n\tdirectory=file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work\n"}


[Trace - 20:21:31.391 PM] Received notification '$/progress'.
Params: {"token":"381114526762976423","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.393 PM] Received notification '$/progress'.
Params: {"token":"4973045475441444873","value":{"kind":"report","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"}}


[Trace - 20:21:31.394 PM] Received request 'window/workDoneProgress/create - (11)'.
Params: {"token":"8369501598094468441"}


[Trace - 20:21:31.396 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","version":2,"diagnostics":[{"range":{"start":{"line":0,"character":0},"end":{"line":0,"character":0}},"severity":1,"source":"go list","message":"/workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"},{"range":{"start":{"line":2,"character":0},"end":{"line":2,"character":0}},"severity":1,"source":"syntax","message":"invalid go version '1.hello': must match format 1.23"}]}


[Trace - 20:21:31.398 PM] Received notification '$/progress'.
Params: {"token":"5977161299719085799","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.398 PM] Sending response 'window/workDoneProgress/create - (11)' in 4ms.
Result: 


[Trace - 20:21:31.401 PM] Received notification '$/progress'.
Params: {"token":"8369501598094468441","value":{"kind":"begin","title":"diagnosing saved files","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.403 PM] Received request 'window/workDoneProgress/create - (12)'.
Params: {"token":"674595638927158918"}


[Trace - 20:21:31.404 PM] Received notification '$/cancelRequest'.
Params: {"id":12}


[Trace - 20:21:31.407 PM] Sending response 'window/workDoneProgress/create - (12)' in 3ms.
Result: 


[Trace - 20:21:31.408 PM] Received notification 'window/logMessage'.
Params: {"type":1,"message":"2021/07/07 20:21:31 workspace packages: diagnosing file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod: /workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"}


[Trace - 20:21:31.410 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]}


[Trace - 20:21:31.410 PM] Received request 'window/workDoneProgress/create - (13)'.
Params: {"token":"6479049701777514107"}


[Trace - 20:21:31.412 PM] Received notification '$/progress'.
Params: {"token":"3809697317681224415","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.414 PM] Sending response 'window/workDoneProgress/create - (13)' in 3ms.
Result: 


[Trace - 20:21:31.415 PM] Received notification '$/progress'.
Params: {"token":"6479049701777514107","value":{"kind":"begin","title":"diagnosing files changed on disk","message":"Calculating file diagnostics..."}}


[Trace - 20:21:31.418 PM] Received request 'window/workDoneProgress/create - (14)'.
Params: {"token":"1579974085404451511"}


[Trace - 20:21:31.420 PM] Sending response 'window/workDoneProgress/create - (14)' in 1ms.
Result: 


[Trace - 20:21:31.421 PM] Received notification '$/progress'.
Params: {"token":"1579974085404451511","value":{"kind":"begin","title":"Load","message":"Loading query=[builtin mod.com/...]"}}


[Trace - 20:21:31.423 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]}


[Trace - 20:21:31.425 PM] Received notification '$/progress'.
Params: {"token":"8369501598094468441","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.569 PM] Received notification 'window/logMessage'.
Params: {"type":3,"message":"2021/07/07 20:21:31 go/packages.Load\n\tsnapshot=6\n\tdirectory=/workdir/tmp/gopls-workspace-mod480105127\n\tquery=[builtin mod.com/...]\n\tpackages=2\n"}


[Trace - 20:21:31.574 PM] Received notification '$/progress'.
Params: {"token":"1579974085404451511","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.589 PM] Received request 'window/workDoneProgress/create - (15)'.
Params: {"token":"8198325534463923292"}


[Trace - 20:21:31.591 PM] Sending response 'window/workDoneProgress/create - (15)' in 2ms.
Result: 


[Trace - 20:21:31.592 PM] Received notification '$/progress'.
Params: {"token":"8198325534463923292","value":{"kind":"begin","title":"Load","message":"Loading query=[builtin mod.com/...]"}}


[Trace - 20:21:31.727 PM] Received notification 'window/logMessage'.
Params: {"type":3,"message":"2021/07/07 20:21:31 go/packages.Load\n\tsnapshot=7\n\tdirectory=/workdir/tmp/gopls-workspace-mod480105127\n\tquery=[builtin mod.com/...]\n\tpackages=2\n"}


[Trace - 20:21:31.730 PM] Received notification '$/progress'.
Params: {"token":"8198325534463923292","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.749 PM] Received notification '$/progress'.
Params: {"token":"4973045475441444873","value":{"kind":"end","message":"Done."}}


[Trace - 20:21:31.751 PM] Received notification 'textDocument/publishDiagnostics'.
Params: {"uri":"file:///workdir/tmp/gopls-regtest-051833570/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]}


[Trace - 20:21:31.753 PM] Received notification '$/progress'.
Params: {"token":"6479049701777514107","value":{"kind":"end","message":"Done."}}


#### End Gopls Test Logs for "TestProgressBarErrors/experimental"
--- FAIL: TestProgressBarErrors (20.52s)
    --- FAIL: TestProgressBarErrors/experimental (20.00s)
        diagnostics_test.go:1539: waiting on:
            	Unmet: no outstanding work
            
            err:context deadline exceeded
            
            state:
            #### log messages (see RPC logs for full text):
            	Error: "2021/07/07 20:21:31 copying workspace dir: /workd...
            	Error: "2021/07/07 20:21:31 errors loading workspace: /wo...
            	Info: "2021/07/07 20:21:31 go env for /workdir/tmp/gopls-...
            	Error: "2021/07/07 20:21:31 getting workspace dir: /workd...
            	Error: "2021/07/07 20:21:31 copying workspace dir: openin...
            	Error: "2021/07/07 20:21:31 errors loading workspace: /wo...
            	Error: "2021/07/07 20:21:31 getting workspace dir: /workd...
            	Error: "2021/07/07 20:21:31 copying workspace dir: openin...
            	Error: "2021/07/07 20:21:31 errors loading workspace: /wo...
            	Error: "2021/07/07 20:21:31 getting workspace dir: /workd...
            	Error: "2021/07/07 20:21:31 copying workspace dir: openin...
            	Error: "2021/07/07 20:21:31 errors loading workspace: /wo...
            	Error: "2021/07/07 20:21:31 workspace packages: diagnosin...
            	Info: "2021/07/07 20:21:31 go/packages.Load\n\tsnapshot=6...
            	Info: "2021/07/07 20:21:31 go/packages.Load\n\tsnapshot=7...
            
            #### diagnostics:
            	go.mod (version 3):
            
            #### outstanding work:
            	!NO NAME(token: 674595638927158918): 0.00
            #### completed work:
            	Load: 2
            	Error loading workspace: 1
            	Setting up workspace: 1
            	diagnosing initial workspace load: 1
            	diagnosing opened files: 1
            	diagnosing changed files: 2
            	diagnosing saved files: 2
            	diagnosing files changed on disk: 2
        runner.go:310: Shutdown: context deadline exceeded
FAIL
FAIL	golang.org/x/tools/gopls/internal/regtest/diagnostics	292.894s
ok  	golang.org/x/tools/gopls/internal/regtest/misc	248.447s
ok  	golang.org/x/tools/gopls/internal/regtest/modfile	47.001s
ok  	golang.org/x/tools/gopls/internal/regtest/watch	56.874s
ok  	golang.org/x/tools/gopls/internal/regtest/workspace	94.255s
?   	golang.org/x/tools/gopls/release	[no test files]
ok  	golang.org/x/tools/gopls/test	129.741s
ok  	golang.org/x/tools/gopls/test/debug	24.934s
FAIL