linux-arm64-packet at c580180744e60d6c84fc0b59d634fcff01290780 building tools at 378b9e1d59e2352276eff57e8153a4ff4053d8a7 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" "golang.org/x/tools/..."] and env ["PATH=/workdir/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin" "HOSTNAME=packet03" "GO_BUILD_KEY_PATH=/buildkey/gobuildkey" "GO_BUILD_KEY_DELETE_AFTER_READ=true" "DEBIAN_FRONTEND=noninteractive" "GOROOT_BOOTSTRAP=/workdir/go1.4" "IN_KUBERNETES=1" "GO_BUILDER_ENV=host-linux-arm64-packet" "HOME=/root" "USER=root" "WORKDIR=/workdir" "GO_BUILDER_NAME=linux-arm64-packet" "GO_BUILDER_FLAKY_NET=1" "GOROOT_BOOTSTRAP=/usr/local/go-bootstrap" "GOROOT=/workdir/go" "GOPATH=/workdir/gopath" "GOPROXY=http://gk3-services-nap-yh2ma9qv-dd1e67b4-kv3d.c.symbolic-datum-552.internal:30157" "GOPROXY=https://proxy.golang.org" "TMPDIR=/workdir/tmp" "GOCACHE=/workdir/gocache" "GOROOT_BOOTSTRAP=/usr/local/go-bootstrap"] in dir /workdir/gopath/src/golang.org/x/tools go: downloading golang.org/x/sys v0.0.0-20210809222454-d867a43fc93e go: downloading golang.org/x/net v0.0.0-20210805182204-aaa1db679c0d go: downloading golang.org/x/xerrors v0.0.0-20200804184101-5ec99f83aff1 go: downloading golang.org/x/mod v0.4.2 go: downloading github.com/yuin/goldmark v1.4.0 go: downloading golang.org/x/sync v0.0.0-20210220032951-036812b2e83c go: downloading golang.org/x/text v0.3.6 ok golang.org/x/tools/benchmark/parse 0.026s ok golang.org/x/tools/blog 0.065s ? 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.105s ok golang.org/x/tools/cmd/bundle 3.304s ok golang.org/x/tools/cmd/callgraph 23.859s ? golang.org/x/tools/cmd/compilebench [no test files] ok golang.org/x/tools/cmd/cover 5.547s ok golang.org/x/tools/cmd/digraph 0.035s ? golang.org/x/tools/cmd/eg [no test files] ok golang.org/x/tools/cmd/file2fuzz 2.678s ok golang.org/x/tools/cmd/fiximports 49.748s ok golang.org/x/tools/cmd/getgo 0.046s ? golang.org/x/tools/cmd/getgo/server [no test files] ok golang.org/x/tools/cmd/go-contrib-init 0.045s ? golang.org/x/tools/cmd/godex [no test files] ok golang.org/x/tools/cmd/godoc 37.743s ? 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 32.697s ? 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.028s ? 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.049s ? 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 66.714s ? golang.org/x/tools/cmd/toolstash [no test files] ok golang.org/x/tools/container/intsets 1.805s ok golang.org/x/tools/cover 0.027s ok golang.org/x/tools/go/analysis 0.056s ok golang.org/x/tools/go/analysis/analysistest 7.683s ok golang.org/x/tools/go/analysis/internal/analysisflags 0.281s ok golang.org/x/tools/go/analysis/internal/checker 1.915s ok golang.org/x/tools/go/analysis/internal/facts 1.430s ok golang.org/x/tools/go/analysis/multichecker 64.119s ok golang.org/x/tools/go/analysis/passes/asmdecl 2.870s ok golang.org/x/tools/go/analysis/passes/assign 5.110s ok golang.org/x/tools/go/analysis/passes/atomic 0.619s ok golang.org/x/tools/go/analysis/passes/atomicalign 0.276s ok golang.org/x/tools/go/analysis/passes/bools 5.045s ok golang.org/x/tools/go/analysis/passes/buildssa 0.283s ok golang.org/x/tools/go/analysis/passes/buildtag 0.452s ok golang.org/x/tools/go/analysis/passes/cgocall 14.809s ok golang.org/x/tools/go/analysis/passes/composite 7.291s ok golang.org/x/tools/go/analysis/passes/copylock 5.002s ok golang.org/x/tools/go/analysis/passes/ctrlflow 9.626s ok golang.org/x/tools/go/analysis/passes/deepequalerrors 10.253s ok golang.org/x/tools/go/analysis/passes/errorsas 8.010s ok golang.org/x/tools/go/analysis/passes/fieldalignment 0.417s ? golang.org/x/tools/go/analysis/passes/fieldalignment/cmd/fieldalignment [no test files] ok golang.org/x/tools/go/analysis/passes/findcall 2.693s ? golang.org/x/tools/go/analysis/passes/findcall/cmd/findcall [no test files] ok golang.org/x/tools/go/analysis/passes/framepointer 0.487s ok golang.org/x/tools/go/analysis/passes/httpresponse 10.619s ok golang.org/x/tools/go/analysis/passes/ifaceassert 5.625s ? 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 2.263s ok golang.org/x/tools/go/analysis/passes/lostcancel 7.727s ? golang.org/x/tools/go/analysis/passes/lostcancel/cmd/lostcancel [no test files] ok golang.org/x/tools/go/analysis/passes/nilfunc 0.999s ok golang.org/x/tools/go/analysis/passes/nilness 0.256s ? golang.org/x/tools/go/analysis/passes/nilness/cmd/nilness [no test files] ok golang.org/x/tools/go/analysis/passes/pkgfact 0.386s ok golang.org/x/tools/go/analysis/passes/printf 7.591s ok golang.org/x/tools/go/analysis/passes/reflectvaluecompare 6.419s ok golang.org/x/tools/go/analysis/passes/shadow 8.012s ? golang.org/x/tools/go/analysis/passes/shadow/cmd/shadow [no test files] ok golang.org/x/tools/go/analysis/passes/shift 0.337s ok golang.org/x/tools/go/analysis/passes/sigchanyzer 6.084s ok golang.org/x/tools/go/analysis/passes/sortslice 3.831s ok golang.org/x/tools/go/analysis/passes/stdmethods 13.286s ok golang.org/x/tools/go/analysis/passes/stringintconv 0.430s ? golang.org/x/tools/go/analysis/passes/stringintconv/cmd/stringintconv [no test files] ok golang.org/x/tools/go/analysis/passes/structtag 7.971s ok golang.org/x/tools/go/analysis/passes/testinggoroutine 7.976s ok golang.org/x/tools/go/analysis/passes/tests 6.774s ok golang.org/x/tools/go/analysis/passes/unmarshal 7.771s ? golang.org/x/tools/go/analysis/passes/unmarshal/cmd/unmarshal [no test files] ok golang.org/x/tools/go/analysis/passes/unreachable 0.546s ok golang.org/x/tools/go/analysis/passes/unsafeptr 10.810s ok golang.org/x/tools/go/analysis/passes/unusedresult 6.396s ok golang.org/x/tools/go/analysis/passes/unusedwrite 0.266s ok golang.org/x/tools/go/analysis/passes/usesgenerics 0.044s ? golang.org/x/tools/go/analysis/singlechecker [no test files] ok golang.org/x/tools/go/analysis/unitchecker 11.482s ok golang.org/x/tools/go/ast/astutil 0.169s ok golang.org/x/tools/go/ast/inspector 0.555s ok golang.org/x/tools/go/buildutil 6.862s ? golang.org/x/tools/go/callgraph [no test files] ok golang.org/x/tools/go/callgraph/cha 0.069s ok golang.org/x/tools/go/callgraph/rta 0.049s ok golang.org/x/tools/go/callgraph/static 0.032s ok golang.org/x/tools/go/callgraph/vta 0.104s ok golang.org/x/tools/go/cfg 0.026s ok golang.org/x/tools/go/expect 0.040s ok golang.org/x/tools/go/gccgoexportdata 0.054s ok golang.org/x/tools/go/gcexportdata 0.094s ? golang.org/x/tools/go/internal/cgo [no test files] ok golang.org/x/tools/go/internal/gccgoimporter 0.073s ok golang.org/x/tools/go/internal/gcimporter 128.789s ? golang.org/x/tools/go/internal/packagesdriver [no test files] ok golang.org/x/tools/go/loader 54.130s ok golang.org/x/tools/go/packages 92.101s ? golang.org/x/tools/go/packages/gopackages [no test files] ok golang.org/x/tools/go/packages/packagestest 1.835s ok golang.org/x/tools/go/pointer 14.505s ok golang.org/x/tools/go/ssa 25.779s ok golang.org/x/tools/go/ssa/interp 2.005s ok golang.org/x/tools/go/ssa/ssautil 8.653s ok golang.org/x/tools/go/types/objectpath 0.018s ok golang.org/x/tools/go/types/typeutil 4.283s ok golang.org/x/tools/go/vcs 0.101s ok golang.org/x/tools/godoc 4.165s ? golang.org/x/tools/godoc/analysis [no test files] ok golang.org/x/tools/godoc/redirect 2.734s ok golang.org/x/tools/godoc/static 5.909s ? golang.org/x/tools/godoc/util [no test files] ok golang.org/x/tools/godoc/vfs 0.022s ok golang.org/x/tools/godoc/vfs/gatefs 0.102s ? 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.068s ? golang.org/x/tools/imports [no test files] ? golang.org/x/tools/internal/analysisinternal [no test files] ok golang.org/x/tools/internal/apidiff 7.860s ok golang.org/x/tools/internal/event 0.029s [no tests to run] ? golang.org/x/tools/internal/event/core [no test files] ok golang.org/x/tools/internal/event/export 0.017s ? 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.053s ok golang.org/x/tools/internal/event/export/ocagent/wire 0.010s ? 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.012s ? golang.org/x/tools/internal/fakenet [no test files] ok golang.org/x/tools/internal/fastwalk 0.310s ok golang.org/x/tools/internal/gocommand 0.074s ok golang.org/x/tools/internal/gopathwalk 0.337s ok golang.org/x/tools/internal/imports 89.215s ok golang.org/x/tools/internal/jsonrpc2 0.189s ok golang.org/x/tools/internal/jsonrpc2/servertest 0.070s ok golang.org/x/tools/internal/jsonrpc2_v2 0.219s ok golang.org/x/tools/internal/lsp 91.988s ok golang.org/x/tools/internal/lsp/analysis/fillreturns 14.900s ok golang.org/x/tools/internal/lsp/analysis/fillstruct 11.324s ok golang.org/x/tools/internal/lsp/analysis/infertypeargs 0.099s ok golang.org/x/tools/internal/lsp/analysis/nonewvars 10.948s ok golang.org/x/tools/internal/lsp/analysis/noresultvalues 0.740s ok golang.org/x/tools/internal/lsp/analysis/simplifycompositelit 0.820s ok golang.org/x/tools/internal/lsp/analysis/simplifyrange 9.520s ok golang.org/x/tools/internal/lsp/analysis/simplifyslice 1.002s ok golang.org/x/tools/internal/lsp/analysis/undeclaredname 7.402s ok golang.org/x/tools/internal/lsp/analysis/unusedparams 12.499s ok golang.org/x/tools/internal/lsp/analysis/useany 0.049s ? golang.org/x/tools/internal/lsp/browser [no test files] ok golang.org/x/tools/internal/lsp/cache 0.178s ok golang.org/x/tools/internal/lsp/cmd 73.207s ? golang.org/x/tools/internal/lsp/cmd/test [no test files] ok golang.org/x/tools/internal/lsp/command 6.735s ? 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.043s ok golang.org/x/tools/internal/lsp/diff/difftest 0.128s ok golang.org/x/tools/internal/lsp/diff/myers 0.027s ok golang.org/x/tools/internal/lsp/fake 0.087s ok golang.org/x/tools/internal/lsp/fuzzy 0.063s ? golang.org/x/tools/internal/lsp/helper [no test files] ? golang.org/x/tools/internal/lsp/lsppos [no test files] ok golang.org/x/tools/internal/lsp/lsprpc 10.155s ok golang.org/x/tools/internal/lsp/mod 0.238s ok golang.org/x/tools/internal/lsp/progress 0.022s ? golang.org/x/tools/internal/lsp/protocol [no test files] ok golang.org/x/tools/internal/lsp/regtest 0.190s ok golang.org/x/tools/internal/lsp/snippet 0.022s ok golang.org/x/tools/internal/lsp/source 67.007s ok golang.org/x/tools/internal/lsp/source/completion 0.121s ok golang.org/x/tools/internal/lsp/template 0.051s ? golang.org/x/tools/internal/lsp/tests [no test files] ok golang.org/x/tools/internal/memoize 0.050s ? 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.055s ok golang.org/x/tools/internal/span 0.047s ok golang.org/x/tools/internal/stack 0.018s ? 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] ok golang.org/x/tools/internal/typeparams 0.022s ? 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.038s ok golang.org/x/tools/present 0.112s ok golang.org/x/tools/refactor/eg 16.708s ok golang.org/x/tools/refactor/importgraph 16.091s ok golang.org/x/tools/refactor/rename 3.544s ? golang.org/x/tools/refactor/satisfy [no test files] ok golang.org/x/tools/txtar 0.072s :: Running /workdir/go/bin/go with args ["/workdir/go/bin/go" "test" "-short" "golang.org/x/tools/gopls/..."] and env ["PATH=/workdir/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin" "HOSTNAME=packet03" "GO_BUILD_KEY_PATH=/buildkey/gobuildkey" "GO_BUILD_KEY_DELETE_AFTER_READ=true" "DEBIAN_FRONTEND=noninteractive" "GOROOT_BOOTSTRAP=/workdir/go1.4" "IN_KUBERNETES=1" "GO_BUILDER_ENV=host-linux-arm64-packet" "HOME=/root" "USER=root" "WORKDIR=/workdir" "GO_BUILDER_NAME=linux-arm64-packet" "GO_BUILDER_FLAKY_NET=1" "GOROOT_BOOTSTRAP=/usr/local/go-bootstrap" "GOROOT=/workdir/go" "GOPATH=/workdir/gopath" "GOPROXY=http://gk3-services-nap-yh2ma9qv-dd1e67b4-kv3d.c.symbolic-datum-552.internal:30157" "GOPROXY=https://proxy.golang.org" "TMPDIR=/workdir/tmp" "GOCACHE=/workdir/gocache" "GOROOT_BOOTSTRAP=/usr/local/go-bootstrap"] in dir /workdir/gopath/src/golang.org/x/tools/gopls go: downloading github.com/sanity-io/litter v1.5.1 go: downloading github.com/google/go-cmp v0.5.6 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.3.0 go: downloading github.com/jba/templatecheck v0.6.0 go: downloading golang.org/x/text v0.3.7 go: downloading github.com/google/safehtml v0.0.2 go: downloading github.com/BurntSushi/toml v0.4.1 ? golang.org/x/tools/gopls [no test files] ok golang.org/x/tools/gopls/doc 15.798s ? golang.org/x/tools/gopls/integration/govim [no test files] ok golang.org/x/tools/gopls/internal/hooks 1.921s ok golang.org/x/tools/gopls/internal/regtest/bench 0.608s ok golang.org/x/tools/gopls/internal/regtest/codelens 77.521s ok golang.org/x/tools/gopls/internal/regtest/completion 76.507s #### Start Gopls Test Logs for "TestProgressBarErrors/experimental" [Trace - 05:04:49.261 AM] 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":{},"typeHierarchy":{}},"window":{"workDoneProgress":true,"showMessage":{"messageActionItem":{}},"showDocument":{"support":false}},"general":{"staleRequestSupport":{"cancel":false,"retryOnContentModified":null},"regularExpressions":{"engine":""},"markdown":{"parser":""}}},"initializationOptions":{"completionBudget":"10s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/gopath","GOPROXY":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/proxy","GOSUMDB":"off"},"expandWorkspaceToModule":true,"verboseWorkDoneProgress":true},"trace":"messages","workspaceFolders":[{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work","name":"work"}]} [Trace - 05:04:49.261 AM] Received response 'initialize - (1)' in 0ms. 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 - 05:04:49.262 AM] Sending notification 'initialized'. Params: {} [Trace - 05:04:49.263 AM] Received request 'window/workDoneProgress/create - (1)'. Params: {"token":"1439228946174806623"} [Trace - 05:04:49.264 AM] Sending response 'window/workDoneProgress/create - (1)' in 0ms. Result: [Trace - 05:04:49.264 AM] Received notification '$/progress'. Params: {"token":"1439228946174806623","value":{"kind":"begin","title":"diagnosing initial workspace load","message":"Calculating diagnostics for initial workspace load..."}} [Trace - 05:04:49.265 AM] Received request 'window/workDoneProgress/create - (2)'. Params: {"token":"4995735335659635616"} [Trace - 05:04:49.265 AM] Sending response 'window/workDoneProgress/create - (2)' in 0ms. Result: [Trace - 05:04:49.266 AM] Received notification '$/progress'. Params: {"token":"4995735335659635616","value":{"kind":"begin","title":"Setting up workspace","message":"Loading packages..."}} [Trace - 05:04:49.267 AM] Received request 'workspace/configuration - (3)'. Params: {"items":[{"scopeUri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work","section":"gopls"}]} [Trace - 05:04:49.268 AM] Sending response 'workspace/configuration - (3)' in 0ms. Result: [{"completionBudget":"10s","diagnosticsDelay":"10ms","env":{"GO111MODULE":"","GOMODCACHE":"","GOPACKAGESDRIVER":"off","GOPATH":"/workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/gopath","GOPROXY":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/proxy","GOSUMDB":"off"},"expandWorkspaceToModule":true,"verboseWorkDoneProgress":true}] [Trace - 05:04:49.300 AM] Received notification 'window/logMessage'. Params: {"type":1,"message":"2021/10/13 05:04:49 copying workspace dir: /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul\n"} [Trace - 05:04:49.301 AM] Received notification '$/progress'. Params: {"token":"4995735335659635616","value":{"kind":"end","message":"Finished loading packages."}} [Trace - 05:04:49.308 AM] Received request 'client/registerCapability - (4)'. Params: {"registrations":[{"id":"workspace/didChangeWatchedFiles-0","method":"workspace/didChangeWatchedFiles","registerOptions":{"watchers":[{"globPattern":"**/*.*tmpl","kind":7},{"globPattern":"**/*.{go,mod,sum,work,tmpl}","kind":7}]}}]} [Trace - 05:04:49.309 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2021/10/13 05:04:49 go env for /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work\n(root /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work)\n(go version go version go1.16.9 linux/arm64)\n(valid build configuration = true)\n(build flags: [])\nGOSUMDB=off\nGONOPROXY=\nGOPRIVATE=\nGOPROXY=file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/proxy\nGOCACHE=/workdir/gocache\nGOINSECURE=\nGONOSUMDB=\nGOFLAGS=\nGOPATH=/workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/gopath\nGOROOT=/workdir/go\nGOMOD=/workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod\nGOMODCACHE=\nGO111MODULE=\n\n"} [Trace - 05:04:49.309 AM] Received request 'window/workDoneProgress/create - (5)'. Params: {"token":"2477080916348470461"} [Trace - 05:04:49.309 AM] Sending response 'client/registerCapability - (4)' in 1ms. Result: [Trace - 05:04:49.310 AM] Received notification 'window/logMessage'. Params: {"type":1,"message":"2021/10/13 05:04:49 errors loading workspace: /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul\n\tsnapshot=0\n\tdirectory=file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work\n"} [Trace - 05:04:49.311 AM] Sending response 'window/workDoneProgress/create - (5)' in 1ms. Result: [Trace - 05:04:49.311 AM] Received notification '$/progress'. Params: {"token":"2477080916348470461","value":{"kind":"begin","title":"Error loading workspace","message":"/workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:1: unknown directive: modul"}} [Trace - 05:04:49.312 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///workdir/tmp/gopls-regtest-328115099/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-328115099/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 - 05:04:49.314 AM] Received notification '$/progress'. Params: {"token":"1439228946174806623","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.315 AM] Sending notification 'textDocument/didOpen'. Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","languageId":"","version":1,"text":"modul mod.com\n\ngo 1.12\n"}} [Trace - 05:04:49.315 AM] Received request 'window/workDoneProgress/create - (6)'. Params: {"token":"8741248032194605601"} [Trace - 05:04:49.316 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":2,"uri":"file:///workdir/tmp/gopls-regtest-328115099/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 - 05:04:49.317 AM] Sending notification 'textDocument/willSave'. Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod"},"reason":1} [Trace - 05:04:49.318 AM] Sending response 'window/workDoneProgress/create - (6)' in 2ms. Result: [Trace - 05:04:49.318 AM] Received notification '$/progress'. Params: {"token":"8741248032194605601","value":{"kind":"begin","title":"diagnosing opened files","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.319 AM] Sending notification 'textDocument/didSave'. Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod"}} [Trace - 05:04:49.321 AM] Received request 'window/workDoneProgress/create - (7)'. Params: {"token":"7174218026911131881"} [Trace - 05:04:49.322 AM] Sending notification 'workspace/didChangeWatchedFiles'. Params: {"changes":[{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","type":2}]} [Trace - 05:04:49.322 AM] Sending response 'window/workDoneProgress/create - (7)' in 1ms. Result: [Trace - 05:04:49.323 AM] Received notification '$/progress'. Params: {"token":"7174218026911131881","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.325 AM] Received request 'window/workDoneProgress/create - (8)'. Params: {"token":"6801475017901952134"} [Trace - 05:04:49.326 AM] Sending response 'window/workDoneProgress/create - (8)' in 0ms. Result: [Trace - 05:04:49.327 AM] Received notification '$/progress'. Params: {"token":"6801475017901952134","value":{"kind":"begin","title":"diagnosing saved files","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.328 AM] Received notification '$/progress'. Params: {"token":"8741248032194605601","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.328 AM] Received notification 'window/logMessage'. Params: {"type":1,"message":"2021/10/13 05:04:49 copying workspace dir: /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"} [Trace - 05:04:49.329 AM] Received notification 'window/logMessage'. Params: {"type":1,"message":"2021/10/13 05:04:49 copying workspace dir: /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"} [Trace - 05:04:49.330 AM] Received notification '$/progress'. Params: {"token":"7174218026911131881","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.330 AM] Received request 'window/workDoneProgress/create - (9)'. Params: {"token":"5508906111153315027"} [Trace - 05:04:49.332 AM] Sending response 'window/workDoneProgress/create - (9)' in 1ms. Result: [Trace - 05:04:49.332 AM] Received notification '$/progress'. Params: {"token":"5508906111153315027","value":{"kind":"begin","title":"diagnosing files changed on disk","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.341 AM] Received notification '$/progress'. Params: {"token":"2477080916348470461","value":{"kind":"report","message":"/workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23"}} [Trace - 05:04:49.342 AM] Received notification 'window/logMessage'. Params: {"type":1,"message":"2021/10/13 05:04:49 errors loading workspace: /workdir/tmp/gopls-regtest-328115099/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-328115099/TestProgressBarErrors/experimental/work\n"} [Trace - 05:04:49.345 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///workdir/tmp/gopls-regtest-328115099/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-328115099/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 - 05:04:49.353 AM] Received notification '$/progress'. Params: {"token":"6801475017901952134","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.354 AM] Sending notification 'textDocument/didChange'. Params: {"textDocument":{"version":3,"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod"},"contentChanges":[{"range":{"start":{"line":2,"character":0},"end":{"line":2,"character":10}},"text":"go 1.12"}]} [Trace - 05:04:49.357 AM] Received request 'window/workDoneProgress/create - (10)'. Params: {"token":"676017818015408657"} [Trace - 05:04:49.357 AM] Sending notification 'textDocument/willSave'. Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod"},"reason":1} [Trace - 05:04:49.360 AM] Sending notification 'textDocument/didSave'. Params: {"textDocument":{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod"}} [Trace - 05:04:49.361 AM] Sending response 'window/workDoneProgress/create - (10)' in 3ms. Result: [Trace - 05:04:49.361 AM] Received notification '$/progress'. Params: {"token":"676017818015408657","value":{"kind":"begin","title":"diagnosing changed files","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.365 AM] Received request 'window/workDoneProgress/create - (11)'. Params: {"token":"4973045475441444873"} [Trace - 05:04:49.366 AM] Sending response 'window/workDoneProgress/create - (11)' in 0ms. Result: [Trace - 05:04:49.366 AM] Received notification '$/progress'. Params: {"token":"4973045475441444873","value":{"kind":"begin","title":"diagnosing saved files","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.377 AM] Received request 'window/workDoneProgress/create - (12)'. Params: {"token":"6330885697262780955"} [Trace - 05:04:49.379 AM] Sending response 'window/workDoneProgress/create - (12)' in 1ms. Result: [Trace - 05:04:49.380 AM] Sending notification 'workspace/didChangeWatchedFiles'. Params: {"changes":[{"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","type":2}]} [Trace - 05:04:49.381 AM] Received notification '$/cancelRequest'. Params: {"id":12} [Trace - 05:04:49.383 AM] Received notification 'window/logMessage'. Params: {"type":1,"message":"2021/10/13 05:04:49 workspace packages: diagnosing file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod: /workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod:3: invalid go version '1.hello': must match format 1.23\n"} [Trace - 05:04:49.383 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]} [Trace - 05:04:49.385 AM] Received notification '$/progress'. Params: {"token":"676017818015408657","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.386 AM] Received request 'window/workDoneProgress/create - (13)'. Params: {"token":"2475840605395901410"} [Trace - 05:04:49.387 AM] Received request 'window/workDoneProgress/create - (14)'. Params: {"token":"381114526762976423"} [Trace - 05:04:49.391 AM] Sending response 'window/workDoneProgress/create - (13)' in 4ms. Result: [Trace - 05:04:49.391 AM] Received notification '$/progress'. Params: {"token":"2475840605395901410","value":{"kind":"begin","title":"diagnosing files changed on disk","message":"Calculating file diagnostics..."}} [Trace - 05:04:49.393 AM] Sending response 'window/workDoneProgress/create - (14)' in 5ms. Result: [Trace - 05:04:49.393 AM] Received notification '$/progress'. Params: {"token":"381114526762976423","value":{"kind":"begin","title":"Load","message":"Loading query=[builtin mod.com/...]"}} [Trace - 05:04:49.395 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]} [Trace - 05:04:49.396 AM] Received notification '$/progress'. Params: {"token":"4973045475441444873","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.679 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2021/10/13 05:04:49 go/packages.Load\n\tsnapshot=5\n\tdirectory=/workdir/tmp/gopls-workspace-mod800842680\n\tquery=[builtin mod.com/...]\n\tpackages=2\n"} [Trace - 05:04:49.680 AM] Received notification '$/progress'. Params: {"token":"381114526762976423","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.693 AM] Received request 'window/workDoneProgress/create - (15)'. Params: {"token":"5977161299719085799"} [Trace - 05:04:49.694 AM] Sending response 'window/workDoneProgress/create - (15)' in 0ms. Result: [Trace - 05:04:49.694 AM] Received notification '$/progress'. Params: {"token":"5977161299719085799","value":{"kind":"begin","title":"Load","message":"Loading query=[builtin mod.com/...]"}} [Trace - 05:04:49.734 AM] Received notification '$/progress'. Params: {"token":"5977161299719085799","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.735 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]} [Trace - 05:04:49.736 AM] Received notification '$/progress'. Params: {"token":"5508906111153315027","value":{"kind":"end","message":"Done."}} [Trace - 05:04:49.749 AM] Received request 'window/workDoneProgress/create - (16)'. Params: {"token":"3809697317681224415"} [Trace - 05:04:49.750 AM] Sending response 'window/workDoneProgress/create - (16)' in 0ms. Result: [Trace - 05:04:49.750 AM] Received notification '$/progress'. Params: {"token":"3809697317681224415","value":{"kind":"begin","title":"Load","message":"Loading query=[builtin mod.com/...]"}} [Trace - 05:04:50.042 AM] Received notification 'window/logMessage'. Params: {"type":3,"message":"2021/10/13 05:04:50 go/packages.Load\n\tsnapshot=7\n\tdirectory=/workdir/tmp/gopls-workspace-mod800842680\n\tquery=[builtin mod.com/...]\n\tpackages=2\n"} [Trace - 05:04:50.042 AM] Received notification '$/progress'. Params: {"token":"3809697317681224415","value":{"kind":"end","message":"Done."}} [Trace - 05:04:50.060 AM] Received notification '$/progress'. Params: {"token":"2477080916348470461","value":{"kind":"end","message":"Done."}} [Trace - 05:04:50.061 AM] Received notification 'textDocument/publishDiagnostics'. Params: {"uri":"file:///workdir/tmp/gopls-regtest-328115099/TestProgressBarErrors/experimental/work/go.mod","version":3,"diagnostics":[]} [Trace - 05:04:50.062 AM] Received notification '$/progress'. Params: {"token":"2475840605395901410","value":{"kind":"end","message":"Done."}} #### End Gopls Test Logs for "TestProgressBarErrors/experimental" --- FAIL: TestProgressBarErrors (26.03s) --- FAIL: TestProgressBarErrors/experimental (25.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/10/13 05:04:49 copying workspace dir: /workd... Info: "2021/10/13 05:04:49 go env for /workdir/tmp/gopls-... Error: "2021/10/13 05:04:49 errors loading workspace: /wo... Error: "2021/10/13 05:04:49 copying workspace dir: /workd... Error: "2021/10/13 05:04:49 copying workspace dir: /workd... Error: "2021/10/13 05:04:49 errors loading workspace: /wo... Error: "2021/10/13 05:04:49 workspace packages: diagnosin... Info: "2021/10/13 05:04:49 go/packages.Load\n\tsnapshot=5... Info: "2021/10/13 05:04:50 go/packages.Load\n\tsnapshot=7... #### diagnostics: go.mod (version 3): #### outstanding work: !NO NAME(token: 6330885697262780955): 0.00 #### completed work: 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 Load: 3 diagnosing files changed on disk: 2 runner.go:312: closing editor: Shutdown: context deadline exceeded FAIL FAIL golang.org/x/tools/gopls/internal/regtest/diagnostics 215.801s ok golang.org/x/tools/gopls/internal/regtest/misc 175.300s ok golang.org/x/tools/gopls/internal/regtest/modfile 101.695s ok golang.org/x/tools/gopls/internal/regtest/watch 74.535s ok golang.org/x/tools/gopls/internal/regtest/workspace 89.575s ? golang.org/x/tools/gopls/release [no test files] ok golang.org/x/tools/gopls/test 63.935s ok golang.org/x/tools/gopls/test/debug 9.897s FAIL